[16:29:50.757] New invocation is queued and will start shortly
[16:29:50.757] Triggered by user:fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com
[16:29:52.132] Starting the invocation (attempt 1)
[16:29:52.168] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/5f1319bb385342c7ef4124b05b83b89ef8588ee8"
[16:29:52.168] Reconstructed gitiles commit from tags
[16:29:52.168] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:29:52.168] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:29:52.170] Buildbucket request:
{
"requestId": "8920715893372222512",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.prod.core.x64-debug"
},
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "9c5564bce81c490d39c6b2bdc2091dccbeeb37e33809792a82821787edf9521c/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "cf22571fa8fc62b7d2d9f1ff570a9a2ae3e934dd",
"project": "integration"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8920715893372222512",
"job": "fuchsia/clang_toolchain.prod.core.x64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "9c5564bce81c490d39c6b2bdc2091dccbeeb37e33809792a82821787edf9521c/578",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "cf22571fa8fc62b7d2d9f1ff570a9a2ae3e934dd",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "5f1319bb385342c7ef4124b05b83b89ef8588ee8"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/5f1319bb385342c7ef4124b05b83b89ef8588ee8",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "d67a6c5d-3d02-4ddf-aae7-d0d1f46473dc",
"title": "clang-linux-x64/0"
}
]
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "5f1319bb385342c7ef4124b05b83b89ef8588ee8"
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "5f1319bb385342c7ef4124b05b83b89ef8588ee8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8920715893372222512"
},
{
"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"
}
}
[16:29:53.138] Scheduled build:
{
"id": "8755285830957945009",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.prod.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-02-23T16:29:52.427389886Z",
"updateTime": "2024-02-23T16:29:52.427389886Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "5f1319bb385342c7ef4124b05b83b89ef8588ee8",
"ref": "refs/heads/main"
}
}
}
[16:29:53.138] Task URL: https://cr-buildbucket.appspot.com/build/8755285830957945009
[16:29:53.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:2:0) after 2m41s
[16:29:54.055] Received PubSub notification, asking Buildbucket for the build status
[16:29:54.076] Build status: SCHEDULED
[16:32:34.290] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:2:0)
[16:32:34.323] Build status: SCHEDULED
[16:32:34.323] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:4:0) after 2m38s
[16:34:53.124] Received PubSub notification, asking Buildbucket for the build status
[16:34:53.154] Build status: STARTED
[16:35:12.349] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:4:0)
[16:35:12.435] Build status: STARTED
[16:35:12.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:6:0) after 8m18s
[16:43:30.580] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:6:0)
[16:43:30.608] Build status: STARTED
[16:43:30.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:7:0) after 4m37s
[16:48:07.673] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:7:0)
[16:48:07.800] Build status: STARTED
[16:48:07.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:8:0) after 7m43s
[16:55:50.815] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:8:0)
[16:55:50.854] Build status: STARTED
[16:55:50.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:9:0) after 7m11s
[17:03:01.868] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:9:0)
[17:03:01.895] Build status: STARTED
[17:03:01.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:10:0) after 5m17s
[17:08:19.016] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:10:0)
[17:08:19.101] Build status: STARTED
[17:08:19.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:11:0) after 8m21s
[17:16:40.118] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:11:0)
[17:16:40.136] Build status: STARTED
[17:16:40.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:12:0) after 6m5s
[17:22:45.203] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:12:0)
[17:22:45.260] Build status: STARTED
[17:22:45.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:13:0) after 7m26s
[17:30:11.282] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:13:0)
[17:30:11.301] Build status: STARTED
[17:30:11.301] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:14:0) after 1m54s
[17:32:05.364] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:14:0)
[17:32:05.382] Build status: STARTED
[17:32:05.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:15:0) after 9m51s
[17:41:56.399] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:15:0)
[17:41:56.427] Build status: STARTED
[17:41:56.427] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:16:0) after 7m23s
[17:49:19.448] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:16:0)
[17:49:19.477] Build status: STARTED
[17:49:19.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:17:0) after 5m5s
[17:54:24.455] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:17:0)
[17:54:24.559] Build status: STARTED
[17:54:24.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:18:0) after 1m47s
[17:56:11.581] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:18:0)
[17:56:11.615] Build status: STARTED
[17:56:11.615] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:19:0) after 9m14s
[18:05:25.644] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:19:0)
[18:05:25.662] Build status: STARTED
[18:05:25.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:20:0) after 9m53s
[18:15:18.740] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:20:0)
[18:15:18.761] Build status: STARTED
[18:15:18.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:21:0) after 5m37s
[18:20:55.832] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:21:0)
[18:20:55.919] Build status: STARTED
[18:20:55.919] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.prod.core.x64-debug:8920715893372222512:22:0) after 8m47s
[18:26:57.706] Received PubSub notification, asking Buildbucket for the build status
[18:26:57.730] Build:
{
"id": "8755285830957945009",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.prod.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-02-23T16:29:52.427389886Z",
"startTime": "2024-02-23T16:34:36.444946Z",
"endTime": "2024-02-23T18:26:55.215283Z",
"updateTime": "2024-02-23T18:26:55.215283Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "5f1319bb385342c7ef4124b05b83b89ef8588ee8",
"ref": "refs/heads/main"
}
}
}
[18:26:57.730] Invocation finished in 1h57m6.98908739s with status SUCCEEDED