[01:16:28.284] New invocation is queued and will start shortly
[01:16:31.225] Starting the invocation (attempt 1)
[01:16:31.253] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/5e38b4fd7e75be3e0ca0bc240aa57d96e1f6e610"
[01:16:31.254] Popped gitiles commit info from properties and tags
[01:16:31.254] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:16:31.254] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:16:31.254] Buildbucket request:
{
"requestId": "8890242180253082640",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-core.x64-release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8890242180253082640",
"job": "fuchsia/fuchsia-profile-core.x64-release-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "1055865443494170fad2297a79c8111f78047029"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@1055865443494170fad2297a79c8111f78047029",
"title": "1055865443494170fad2297a79c8111f78047029",
"url": "https://fuchsia.googlesource.com/fuchsia/+/1055865443494170fad2297a79c8111f78047029"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "997a3198a33fae6dd8d95b1015b13ba931b687cb"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@997a3198a33fae6dd8d95b1015b13ba931b687cb",
"title": "997a3198a33fae6dd8d95b1015b13ba931b687cb",
"url": "https://fuchsia.googlesource.com/fuchsia/+/997a3198a33fae6dd8d95b1015b13ba931b687cb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a92e98645dfa694160b9716ef2d84ff0c30a8344"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a92e98645dfa694160b9716ef2d84ff0c30a8344",
"title": "a92e98645dfa694160b9716ef2d84ff0c30a8344",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a92e98645dfa694160b9716ef2d84ff0c30a8344"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "fed3bfdbd0e0ff8eb2e8797ec16fd01a6d01b5ad"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@fed3bfdbd0e0ff8eb2e8797ec16fd01a6d01b5ad",
"title": "fed3bfdbd0e0ff8eb2e8797ec16fd01a6d01b5ad",
"url": "https://fuchsia.googlesource.com/fuchsia/+/fed3bfdbd0e0ff8eb2e8797ec16fd01a6d01b5ad"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "b37a5f4057760f72cd50792d6e587c28f4fd7afe"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@b37a5f4057760f72cd50792d6e587c28f4fd7afe",
"title": "b37a5f4057760f72cd50792d6e587c28f4fd7afe",
"url": "https://fuchsia.googlesource.com/fuchsia/+/b37a5f4057760f72cd50792d6e587c28f4fd7afe"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "ec0e449f791e5ba4ef59858d365d36de9faf33f2"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@ec0e449f791e5ba4ef59858d365d36de9faf33f2",
"title": "ec0e449f791e5ba4ef59858d365d36de9faf33f2",
"url": "https://fuchsia.googlesource.com/fuchsia/+/ec0e449f791e5ba4ef59858d365d36de9faf33f2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "e23daa60be93dbd0dbec4c0b9534d7228da0e3a9"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@e23daa60be93dbd0dbec4c0b9534d7228da0e3a9",
"title": "e23daa60be93dbd0dbec4c0b9534d7228da0e3a9",
"url": "https://fuchsia.googlesource.com/fuchsia/+/e23daa60be93dbd0dbec4c0b9534d7228da0e3a9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "026e18a07f0fb3125104196f2820699fb8c3c99e"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@026e18a07f0fb3125104196f2820699fb8c3c99e",
"title": "026e18a07f0fb3125104196f2820699fb8c3c99e",
"url": "https://fuchsia.googlesource.com/fuchsia/+/026e18a07f0fb3125104196f2820699fb8c3c99e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "25d8f88e372d5aab516723373428b2267d4955e4"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@25d8f88e372d5aab516723373428b2267d4955e4",
"title": "25d8f88e372d5aab516723373428b2267d4955e4",
"url": "https://fuchsia.googlesource.com/fuchsia/+/25d8f88e372d5aab516723373428b2267d4955e4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "4dfbf99edb2e9bd5cfc6bfd346fc241117890cd6"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@4dfbf99edb2e9bd5cfc6bfd346fc241117890cd6",
"title": "4dfbf99edb2e9bd5cfc6bfd346fc241117890cd6",
"url": "https://fuchsia.googlesource.com/fuchsia/+/4dfbf99edb2e9bd5cfc6bfd346fc241117890cd6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "5e38b4fd7e75be3e0ca0bc240aa57d96e1f6e610"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@5e38b4fd7e75be3e0ca0bc240aa57d96e1f6e610",
"title": "5e38b4fd7e75be3e0ca0bc240aa57d96e1f6e610",
"url": "https://fuchsia.googlesource.com/fuchsia/+/5e38b4fd7e75be3e0ca0bc240aa57d96e1f6e610"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "5e38b4fd7e75be3e0ca0bc240aa57d96e1f6e610",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8890242180253082640"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/fuchsia-profile-core.x64-release-af244ac6"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:16:31.624] Scheduled build:
{
"id": "8724812116418787201",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-01-25T01:16:31.309893203Z",
"updateTime": "2025-01-25T01:16:31.309893203Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "5e38b4fd7e75be3e0ca0bc240aa57d96e1f6e610",
"ref": "refs/heads/main"
}
}
}
[01:16:31.624] Task URL: https://cr-buildbucket.appspot.com/build/8724812116418787201
[01:16:31.624] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:2:0) after 1m22s
[01:16:32.619] Received PubSub notification, asking Buildbucket for the build status
[01:16:32.646] Build status: SCHEDULED
[01:16:35.992] Received PubSub notification, asking Buildbucket for the build status
[01:16:36.019] Build status: STARTED
[01:17:53.648] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:2:0)
[01:17:53.678] Build status: STARTED
[01:17:53.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:5:0) after 2m5s
[01:19:58.654] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:5:0)
[01:19:58.677] Build status: STARTED
[01:19:58.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:6:0) after 5m11s
[01:25:09.734] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:6:0)
[01:25:09.762] Build status: STARTED
[01:25:09.762] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:7:0) after 2m22s
[01:27:31.855] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:7:0)
[01:27:31.881] Build status: STARTED
[01:27:31.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:8:0) after 1m33s
[01:29:04.941] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:8:0)
[01:29:04.959] Build status: STARTED
[01:29:04.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:9:0) after 1m56s
[01:31:00.985] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:9:0)
[01:31:01.008] Build status: STARTED
[01:31:01.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:10:0) after 5m8s
[01:36:09.018] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:10:0)
[01:36:09.066] Build status: STARTED
[01:36:09.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:11:0) after 8m27s
[01:44:36.104] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:11:0)
[01:44:36.125] Build status: STARTED
[01:44:36.125] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:12:0) after 2m25s
[01:47:01.191] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:12:0)
[01:47:01.256] Build status: STARTED
[01:47:01.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:13:0) after 4m14s
[01:51:15.342] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:13:0)
[01:51:15.358] Build status: STARTED
[01:51:15.358] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:14:0) after 8m41s
[01:59:56.390] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:14:0)
[01:59:56.428] Build status: STARTED
[01:59:56.428] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:15:0) after 9m4s
[02:09:00.496] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:15:0)
[02:09:00.518] Build status: STARTED
[02:09:00.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:16:0) after 9m7s
[02:18:07.546] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:16:0)
[02:18:07.601] Build status: STARTED
[02:18:07.601] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:17:0) after 4m27s
[02:22:34.655] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:17:0)
[02:22:34.675] Build status: STARTED
[02:22:34.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:18:0) after 7m23s
[02:29:57.750] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:18:0)
[02:29:57.802] Build status: STARTED
[02:29:57.802] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:19:0) after 4m40s
[02:34:38.132] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:19:0)
[02:34:38.186] Build status: STARTED
[02:34:38.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:20:0) after 2m57s
[02:37:35.285] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:20:0)
[02:37:35.347] Build status: STARTED
[02:37:35.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:21:0) after 6m34s
[02:44:10.037] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:21:0)
[02:44:10.061] Build status: STARTED
[02:44:10.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:22:0) after 6m15s
[02:50:25.248] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:22:0)
[02:50:25.270] Build status: STARTED
[02:50:25.270] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:23:0) after 6m12s
[02:56:37.543] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:23:0)
[02:56:37.591] Build status: STARTED
[02:56:37.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:24:0) after 8m7s
[03:04:44.611] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:24:0)
[03:04:44.633] Build status: STARTED
[03:04:44.633] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890242180253082640:25:0) after 8m10s
[03:10:49.383] Received PubSub notification, asking Buildbucket for the build status
[03:10:49.403] Build:
{
"id": "8724812116418787201",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-01-25T01:16:31.309893203Z",
"startTime": "2025-01-25T01:16:35.798788676Z",
"endTime": "2025-01-25T03:10:47.379843644Z",
"updateTime": "2025-01-25T03:10:47.379843644Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "5e38b4fd7e75be3e0ca0bc240aa57d96e1f6e610",
"ref": "refs/heads/main"
}
}
}
[03:10:49.403] Invocation finished in 1h54m21.133617167s with status SUCCEEDED