[02:00:32.880] New invocation is queued and will start shortly
[02:00:32.880] Triggered by user:tools-ci-builder@fuchsia-infra.iam.gserviceaccount.com
[02:00:34.116] Starting the invocation (attempt 1)
[02:00:34.153] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/f2b91ccbc27cb06369aa2dd934ff219e156408a8"
[02:00:34.153] Reconstructed gitiles commit from tags
[02:00:34.153] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:00:34.154] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:00:34.154] Buildbucket request:
{
"requestId": "8893863285848702832",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_toolchain.core.x64-debug"
},
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "62008d857ad0519bbc83c43a1fabda6468823298",
"project": "integration",
"ref": "refs/heads/releases/canary"
},
"rust_toolchain": {
"cas_digest": "52349d38070fc4211a05130d12f482ab5323e852f79e050ee6c6c48590b96abf/394",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8893863285848702832",
"job": "fuchsia/rust_toolchain.core.x64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "62008d857ad0519bbc83c43a1fabda6468823298",
"project": "integration",
"ref": "refs/heads/releases/canary"
},
"rust_toolchain": {
"cas_digest": "b7ef74745745dbca47e3b5502b4883e7ed63046c496f2663e34d1446cf94a393/394",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"rust_toolchain": {
"git_repository": "https://fuchsia.googlesource.com/third_party/rust",
"git_revision": "f2b91ccbc27cb06369aa2dd934ff219e156408a8"
}
},
"tags": [
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/f2b91ccbc27cb06369aa2dd934ff219e156408a8",
"gitiles_ref:refs/heads/main",
"parent_buildername:rust-x64-linux",
"user_agent:recipe"
]
},
"id": "f0094783-93a2-43c4-a471-e8ce8a0ce64b",
"title": "rust-x64-linux/0"
},
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "62008d857ad0519bbc83c43a1fabda6468823298",
"project": "integration",
"ref": "refs/heads/releases/canary"
},
"rust_toolchain": {
"cas_digest": "52349d38070fc4211a05130d12f482ab5323e852f79e050ee6c6c48590b96abf/394",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"rust_toolchain": {
"git_repository": "https://fuchsia.googlesource.com/third_party/rust",
"git_revision": "f2b91ccbc27cb06369aa2dd934ff219e156408a8"
}
},
"tags": [
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/f2b91ccbc27cb06369aa2dd934ff219e156408a8",
"gitiles_ref:refs/heads/main",
"parent_buildername:rust-x64-linux-bootstrap",
"user_agent:recipe"
]
},
"id": "c15b8a66-fad4-4c18-b4e8-0751ef85971d",
"title": "rust-x64-linux-bootstrap/0"
}
]
},
"rust_toolchain": {
"git_repository": "https://fuchsia.googlesource.com/third_party/rust",
"git_revision": "f2b91ccbc27cb06369aa2dd934ff219e156408a8"
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "f2b91ccbc27cb06369aa2dd934ff219e156408a8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8893863285848702832"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/rust_toolchain.core.x64-debug"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "rust-x64-linux-bootstrap"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:00:34.659] Scheduled build:
{
"id": "8728433223741709633",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_toolchain.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-12-16T02:00:34.258585959Z",
"updateTime": "2024-12-16T02:00:34.258585959Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "f2b91ccbc27cb06369aa2dd934ff219e156408a8",
"ref": "refs/heads/main"
}
}
}
[02:00:34.659] Task URL: https://cr-buildbucket.appspot.com/build/8728433223741709633
[02:00:34.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:2:0) after 7m58s
[02:00:34.828] Received PubSub notification, asking Buildbucket for the build status
[02:00:34.851] Build status: SCHEDULED
[02:00:41.669] Received PubSub notification, asking Buildbucket for the build status
[02:00:41.685] Build status: STARTED
[02:08:32.653] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:2:0)
[02:08:32.707] Build status: STARTED
[02:08:32.707] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:5:0) after 3m30s
[02:12:02.843] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:5:0)
[02:12:02.866] Build status: STARTED
[02:12:02.866] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:6:0) after 1m8s
[02:13:11.104] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:6:0)
[02:13:11.160] Build status: STARTED
[02:13:11.160] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:7:0) after 9m12s
[02:22:23.175] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:7:0)
[02:22:23.194] Build status: STARTED
[02:22:23.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:8:0) after 4m11s
[02:26:34.202] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:8:0)
[02:26:34.240] Build status: STARTED
[02:26:34.240] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:9:0) after 3m8s
[02:29:42.254] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:9:0)
[02:29:42.286] Build status: STARTED
[02:29:42.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:10:0) after 8m7s
[02:37:49.552] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:10:0)
[02:37:49.571] Build status: STARTED
[02:37:49.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:11:0) after 9m58s
[02:47:47.587] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:11:0)
[02:47:47.678] Build status: STARTED
[02:47:47.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:12:0) after 9m37s
[02:57:24.894] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:12:0)
[02:57:24.913] Build status: STARTED
[02:57:24.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:13:0) after 8m30s
[03:05:54.927] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:13:0)
[03:05:54.954] Build status: STARTED
[03:05:54.954] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:14:0) after 7m38s
[03:13:33.179] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:14:0)
[03:13:33.221] Build status: STARTED
[03:13:33.221] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:15:0) after 2m34s
[03:16:07.311] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:15:0)
[03:16:07.328] Build status: STARTED
[03:16:07.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:16:0) after 2m10s
[03:18:17.346] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:16:0)
[03:18:17.372] Build status: STARTED
[03:18:17.372] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:17:0) after 6m53s
[03:25:10.400] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:17:0)
[03:25:10.483] Build status: STARTED
[03:25:10.483] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:18:0) after 1m38s
[03:26:48.661] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:18:0)
[03:26:48.677] Build status: STARTED
[03:26:48.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:19:0) after 2m56s
[03:29:44.749] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:19:0)
[03:29:44.767] Build status: STARTED
[03:29:44.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:20:0) after 8m47s
[03:38:31.816] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:20:0)
[03:38:31.850] Build status: STARTED
[03:38:31.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:21:0) after 3m38s
[03:42:09.966] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:21:0)
[03:42:10.022] Build status: STARTED
[03:42:10.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:22:0) after 7m18s
[03:49:28.549] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:22:0)
[03:49:28.564] Build status: STARTED
[03:49:28.564] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:23:0) after 6m0s
[03:55:28.581] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:23:0)
[03:55:28.603] Build status: STARTED
[03:55:28.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:24:0) after 6m54s
[04:02:22.739] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:24:0)
[04:02:22.761] Build status: STARTED
[04:02:22.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:25:0) after 4m14s
[04:06:37.103] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:25:0)
[04:06:37.121] Build status: STARTED
[04:06:37.121] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8893863285848702832:26:0) after 8m50s
[04:08:15.282] Received PubSub notification, asking Buildbucket for the build status
[04:08:15.357] Build:
{
"id": "8728433223741709633",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_toolchain.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-12-16T02:00:34.258585959Z",
"startTime": "2024-12-16T02:00:41.474957763Z",
"endTime": "2024-12-16T04:08:14.908195238Z",
"updateTime": "2024-12-16T04:08:14.908195238Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "f2b91ccbc27cb06369aa2dd934ff219e156408a8",
"ref": "refs/heads/main"
}
}
}
[04:08:15.357] Invocation finished in 2h7m42.492773926s with status SUCCEEDED