[06:20:05.491] New invocation is queued and will start shortly
[06:20:05.491] Triggered by user:fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com
[06:20:06.595] Starting the invocation (attempt 1)
[06:20:06.653] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/65688274b14f5d0e7dabbbaf9f3fd135646ef1d1"
[06:20:06.653] Reconstructed gitiles commit from tags
[06:20:06.653] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:20:06.653] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:20:06.654] Buildbucket request:
{
"requestId": "8900098147458719984",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.prod.core.x64-debug"
},
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "cf3c799ebfe989fa5497a7e6019be47bb35ce1b9c5441fcf7f26e4cc80bffdd3/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "c38c17ca8b571c9757ceb81954b2991e0f3a9938",
"project": "integration",
"ref": "refs/heads/releases/canary"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8900098147458719984",
"job": "fuchsia/clang_toolchain.prod.core.x64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "cf3c799ebfe989fa5497a7e6019be47bb35ce1b9c5441fcf7f26e4cc80bffdd3/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "c38c17ca8b571c9757ceb81954b2991e0f3a9938",
"project": "integration",
"ref": "refs/heads/releases/canary"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "65688274b14f5d0e7dabbbaf9f3fd135646ef1d1"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/65688274b14f5d0e7dabbbaf9f3fd135646ef1d1",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "5bfc5028-b4b6-4bc3-817f-51ac34d63652",
"title": "clang-linux-x64/0"
}
]
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "65688274b14f5d0e7dabbbaf9f3fd135646ef1d1"
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "65688274b14f5d0e7dabbbaf9f3fd135646ef1d1",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8900098147458719984"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/clang_toolchain.prod.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"
}
}
[06:20:07.239] Scheduled build:
{
"id": "8734668085502911905",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.prod.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-08T06:20:06.730756677Z",
"updateTime": "2024-10-08T06:20:06.730756677Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "65688274b14f5d0e7dabbbaf9f3fd135646ef1d1",
"ref": "refs/heads/main"
}
}
}
[06:20:07.239] Task URL: https://cr-buildbucket.appspot.com/build/8734668085502911905
[06:20:07.239] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:2:0) after 4m26s
[06:20:07.574] Received PubSub notification, asking Buildbucket for the build status
[06:20:07.657] Build status: SCHEDULED
[06:20:13.420] Received PubSub notification, asking Buildbucket for the build status
[06:20:13.449] Build status: STARTED
[06:24:33.254] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:2:0)
[06:24:33.270] Build status: STARTED
[06:24:33.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:5:0) after 8m50s
[06:33:23.286] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:5:0)
[06:33:23.302] Build status: STARTED
[06:33:23.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:6:0) after 4m1s
[06:37:24.324] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:6:0)
[06:37:24.342] Build status: STARTED
[06:37:24.342] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:7:0) after 1m49s
[06:39:13.371] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:7:0)
[06:39:13.403] Build status: STARTED
[06:39:13.403] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:8:0) after 2m20s
[06:41:33.506] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:8:0)
[06:41:33.560] Build status: STARTED
[06:41:33.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:9:0) after 8m1s
[06:49:34.675] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:9:0)
[06:49:34.699] Build status: STARTED
[06:49:34.699] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:10:0) after 6m36s
[06:56:10.773] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:10:0)
[06:56:10.792] Build status: STARTED
[06:56:10.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:11:0) after 7m13s
[07:03:23.788] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:11:0)
[07:03:23.812] Build status: STARTED
[07:03:23.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:12:0) after 9m53s
[07:13:16.827] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:12:0)
[07:13:16.846] Build status: STARTED
[07:13:16.846] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:13:0) after 6m52s
[07:20:08.962] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:13:0)
[07:20:08.980] Build status: STARTED
[07:20:08.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:14:0) after 4m28s
[07:24:37.278] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:14:0)
[07:24:37.299] Build status: STARTED
[07:24:37.299] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:15:0) after 4m55s
[07:29:32.551] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:15:0)
[07:29:32.571] Build status: STARTED
[07:29:32.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:16:0) after 8m49s
[07:38:21.636] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:16:0)
[07:38:21.655] Build status: STARTED
[07:38:21.655] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:17:0) after 5m29s
[07:43:50.724] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:17:0)
[07:43:50.874] Build status: STARTED
[07:43:50.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:18:0) after 7m50s
[07:51:40.897] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:18:0)
[07:51:40.913] Build status: STARTED
[07:51:40.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:19:0) after 8m19s
[08:00:00.040] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:19:0)
[08:00:00.063] Build status: STARTED
[08:00:00.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:20:0) after 6m42s
[08:06:42.084] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:20:0)
[08:06:42.105] Build status: STARTED
[08:06:42.105] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:21:0) after 9m1s
[08:15:43.452] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:21:0)
[08:15:43.507] Build status: STARTED
[08:15:43.508] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:22:0) after 7m34s
[08:23:17.554] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:22:0)
[08:23:17.573] Build status: STARTED
[08:23:17.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:23:0) after 7m5s
[08:30:22.588] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:23:0)
[08:30:22.686] Build status: STARTED
[08:30:22.686] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:24:0) after 3m20s
[08:33:42.756] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:24:0)
[08:33:42.771] Build status: STARTED
[08:33:42.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:25:0) after 1m5s
[08:34:47.791] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:25:0)
[08:34:47.813] Build status: STARTED
[08:34:47.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:26:0) after 2m10s
[08:36:57.871] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:26:0)
[08:36:57.887] Build status: STARTED
[08:36:57.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:27:0) after 3m49s
[08:40:46.984] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:27:0)
[08:40:47.243] Build status: STARTED
[08:40:47.243] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:28:0) after 4m47s
[08:45:34.296] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:28:0)
[08:45:34.317] Build status: STARTED
[08:45:34.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8900098147458719984:29:0) after 8m40s
[08:50:12.147] Received PubSub notification, asking Buildbucket for the build status
[08:50:12.168] Build:
{
"id": "8734668085502911905",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.prod.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-08T06:20:06.730756677Z",
"startTime": "2024-10-08T06:20:12.957991314Z",
"endTime": "2024-10-08T08:50:11.721307321Z",
"updateTime": "2024-10-08T08:50:11.721307321Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "65688274b14f5d0e7dabbbaf9f3fd135646ef1d1",
"ref": "refs/heads/main"
}
}
}
[08:50:12.168] Invocation finished in 2h30m6.686688852s with status SUCCEEDED