[11:55:50.909] New invocation is queued and will start shortly
[11:55:50.909] Triggered by user:fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com
[11:55:52.036] Starting the invocation (attempt 1)
[11:55:52.059] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/3242e7784190b6e544ec4018706fa5923a20a98e"
[11:55:52.059] Reconstructed gitiles commit from tags
[11:55:52.059] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:55:52.059] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:55:52.059] Buildbucket request:
{
"requestId": "8902885529421600128",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.prod.core.x64-debug"
},
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "26e5c3551afbfa8e2a9793e2b4ac5f31a2a0c8615c6e79765691e1b3d1447f0d/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "67166a65bf3e2fb37def95efc4a45fff1cb8b342",
"project": "integration",
"ref": "refs/heads/releases/canary"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8902885529421600128",
"job": "fuchsia/clang_toolchain.prod.core.x64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "26e5c3551afbfa8e2a9793e2b4ac5f31a2a0c8615c6e79765691e1b3d1447f0d/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "67166a65bf3e2fb37def95efc4a45fff1cb8b342",
"project": "integration",
"ref": "refs/heads/releases/canary"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "3242e7784190b6e544ec4018706fa5923a20a98e"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/3242e7784190b6e544ec4018706fa5923a20a98e",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "e2badc0b-f0a7-4094-9fbb-9d1b152f1e13",
"title": "clang-linux-x64/0"
}
]
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "3242e7784190b6e544ec4018706fa5923a20a98e"
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "3242e7784190b6e544ec4018706fa5923a20a98e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8902885529421600128"
},
{
"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"
}
}
[11:55:52.622] Scheduled build:
{
"id": "8737455467416697441",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.prod.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-07T11:55:52.189645127Z",
"updateTime": "2024-09-07T11:55:52.189645127Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "3242e7784190b6e544ec4018706fa5923a20a98e",
"ref": "refs/heads/main"
}
}
}
[11:55:52.622] Task URL: https://cr-buildbucket.appspot.com/build/8737455467416697441
[11:55:52.622] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:2:0) after 8m4s
[11:55:53.513] Received PubSub notification, asking Buildbucket for the build status
[11:55:53.544] Build status: SCHEDULED
[11:55:57.629] Received PubSub notification, asking Buildbucket for the build status
[11:55:57.653] Build status: STARTED
[12:03:56.637] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:2:0)
[12:03:56.694] Build status: STARTED
[12:03:56.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:5:0) after 1m30s
[12:05:26.710] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:5:0)
[12:05:26.729] Build status: STARTED
[12:05:26.729] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:6:0) after 2m48s
[12:08:14.745] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:6:0)
[12:08:14.765] Build status: STARTED
[12:08:14.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:7:0) after 7m37s
[12:15:51.779] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:7:0)
[12:15:51.796] Build status: STARTED
[12:15:51.796] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:8:0) after 6m33s
[12:22:24.851] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:8:0)
[12:22:24.890] Build status: STARTED
[12:22:24.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:9:0) after 3m53s
[12:26:17.906] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:9:0)
[12:26:17.926] Build status: STARTED
[12:26:17.926] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:10:0) after 4m15s
[12:30:32.947] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:10:0)
[12:30:32.967] Build status: STARTED
[12:30:32.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:11:0) after 7m43s
[12:38:16.149] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:11:0)
[12:38:16.174] Build status: STARTED
[12:38:16.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:12:0) after 6m56s
[12:45:12.425] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:12:0)
[12:45:12.442] Build status: STARTED
[12:45:12.442] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:13:0) after 2m19s
[12:47:31.464] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:13:0)
[12:47:31.482] Build status: STARTED
[12:47:31.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:14:0) after 6m43s
[12:54:14.485] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:14:0)
[12:54:14.504] Build status: STARTED
[12:54:14.504] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:15:0) after 9m42s
[13:03:56.655] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:15:0)
[13:03:56.675] Build status: STARTED
[13:03:56.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:16:0) after 9m15s
[13:13:11.726] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:16:0)
[13:13:11.743] Build status: STARTED
[13:13:11.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:17:0) after 1m54s
[13:15:05.843] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:17:0)
[13:15:05.979] Build status: STARTED
[13:15:05.979] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:18:0) after 9m15s
[13:24:20.995] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:18:0)
[13:24:21.027] Build status: STARTED
[13:24:21.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:19:0) after 4m55s
[13:29:16.010] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:19:0)
[13:29:16.026] Build status: STARTED
[13:29:16.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:20:0) after 3m35s
[13:32:51.056] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:20:0)
[13:32:51.078] Build status: STARTED
[13:32:51.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:21:0) after 8m52s
[13:41:43.357] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:21:0)
[13:41:43.374] Build status: STARTED
[13:41:43.374] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:22:0) after 3m3s
[13:44:46.390] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:22:0)
[13:44:46.416] Build status: STARTED
[13:44:46.416] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:23:0) after 9m53s
[13:54:39.429] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:23:0)
[13:54:39.446] Build status: STARTED
[13:54:39.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:24:0) after 7m46s
[14:02:25.473] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:24:0)
[14:02:25.491] Build status: STARTED
[14:02:25.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:25:0) after 1m13s
[14:03:38.507] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:25:0)
[14:03:38.528] Build status: STARTED
[14:03:38.528] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:26:0) after 7m11s
[14:10:49.746] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:26:0)
[14:10:49.764] Build status: STARTED
[14:10:49.764] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:27:0) after 6m58s
[14:17:47.878] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:27:0)
[14:17:47.898] Build status: STARTED
[14:17:47.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:28:0) after 1m13s
[14:19:00.921] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:28:0)
[14:19:00.948] Build status: STARTED
[14:19:00.948] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8902885529421600128:29:0) after 3m46s
[14:20:40.931] Received PubSub notification, asking Buildbucket for the build status
[14:20:41.003] Build:
{
"id": "8737455467416697441",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.prod.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-07T11:55:52.189645127Z",
"startTime": "2024-09-07T11:55:57.460836390Z",
"endTime": "2024-09-07T14:20:40.401442485Z",
"updateTime": "2024-09-07T14:20:40.401442485Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "3242e7784190b6e544ec4018706fa5923a20a98e",
"ref": "refs/heads/main"
}
}
}
[14:20:41.003] Invocation finished in 2h24m50.10973504s with status SUCCEEDED