[01:19:49.226] New invocation is queued and will start shortly
[01:19:49.226] Triggered by user:tools-ci-builder@fuchsia-infra.iam.gserviceaccount.com
[01:19:50.408] Starting the invocation (attempt 1)
[01:19:50.435] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/fb898629a26e4acec59c928ce3ec00a62675d1cc"
[01:19:50.435] Reconstructed gitiles commit from tags
[01:19:50.435] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:19:50.435] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:19:50.435] Buildbucket request:
{
"requestId": "8915427926212471520",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_toolchain.core.x64-debug"
},
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "3098601abc19a2f6131b2e7ab3fb03c56f031b57",
"project": "integration"
},
"rust_toolchain": {
"cas_digest": "87b762fffeb02d14a74305499480f8a0cde68797e474e340b6f42d4e4d2dbb47/394",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8915427926212471520",
"job": "fuchsia/rust_toolchain.core.x64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "3098601abc19a2f6131b2e7ab3fb03c56f031b57",
"project": "integration"
},
"rust_toolchain": {
"cas_digest": "d2f613a84240c94344b7aa71d77323f42b2d3df8b9c6391aabab73960aad548d/394",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"rust_toolchain": {
"git_repository": "https://fuchsia.googlesource.com/third_party/rust",
"git_revision": "fb898629a26e4acec59c928ce3ec00a62675d1cc"
}
},
"tags": [
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/fb898629a26e4acec59c928ce3ec00a62675d1cc",
"gitiles_ref:refs/heads/main",
"parent_buildername:rust-x64-linux-bootstrap",
"user_agent:recipe"
]
},
"id": "76da0473-fbed-4c43-a988-66ed44ac596b",
"title": "rust-x64-linux-bootstrap/0"
},
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "3098601abc19a2f6131b2e7ab3fb03c56f031b57",
"project": "integration"
},
"rust_toolchain": {
"cas_digest": "87b762fffeb02d14a74305499480f8a0cde68797e474e340b6f42d4e4d2dbb47/394",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"rust_toolchain": {
"git_repository": "https://fuchsia.googlesource.com/third_party/rust",
"git_revision": "fb898629a26e4acec59c928ce3ec00a62675d1cc"
}
},
"tags": [
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/fb898629a26e4acec59c928ce3ec00a62675d1cc",
"gitiles_ref:refs/heads/main",
"parent_buildername:rust-x64-linux",
"user_agent:recipe"
]
},
"id": "a1af0fc8-f611-49da-a2ec-c2d83b1bb28e",
"title": "rust-x64-linux/0"
}
]
},
"rust_toolchain": {
"git_repository": "https://fuchsia.googlesource.com/third_party/rust",
"git_revision": "fb898629a26e4acec59c928ce3ec00a62675d1cc"
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "fb898629a26e4acec59c928ce3ec00a62675d1cc",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8915427926212471520"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/rust_toolchain.core.x64-debug"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "rust-x64-linux"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:19:50.893] Scheduled build:
{
"id": "8749997864196881985",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_toolchain.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-04-22T01:19:50.513675985Z",
"updateTime": "2024-04-22T01:19:50.513675985Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "fb898629a26e4acec59c928ce3ec00a62675d1cc",
"ref": "refs/heads/main"
}
},
"canary": true
}
[01:19:50.893] Task URL: https://cr-buildbucket.appspot.com/build/8749997864196881985
[01:19:50.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:2:0) after 3m25s
[01:19:51.843] Received PubSub notification, asking Buildbucket for the build status
[01:19:51.862] Build status: SCHEDULED
[01:19:59.244] Received PubSub notification, asking Buildbucket for the build status
[01:19:59.260] Build status: STARTED
[01:23:15.953] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:2:0)
[01:23:16.217] Build status: STARTED
[01:23:16.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:5:0) after 6m35s
[01:29:51.555] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:5:0)
[01:29:51.577] Build status: STARTED
[01:29:51.577] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:6:0) after 1m24s
[01:31:15.589] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:6:0)
[01:31:15.615] Build status: STARTED
[01:31:15.615] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:7:0) after 3m12s
[01:34:27.638] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:7:0)
[01:34:27.656] Build status: STARTED
[01:34:27.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:8:0) after 1m36s
[01:36:03.872] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:8:0)
[01:36:03.897] Build status: STARTED
[01:36:03.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:9:0) after 5m31s
[01:41:34.931] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:9:0)
[01:41:34.954] Build status: STARTED
[01:41:34.954] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:10:0) after 5m14s
[01:46:49.039] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:10:0)
[01:46:49.058] Build status: STARTED
[01:46:49.058] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:11:0) after 2m59s
[01:49:48.079] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:11:0)
[01:49:48.098] Build status: STARTED
[01:49:48.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:12:0) after 7m34s
[01:57:22.295] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:12:0)
[01:57:22.325] Build status: STARTED
[01:57:22.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:13:0) after 2m18s
[01:59:40.538] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:13:0)
[01:59:40.595] Build status: STARTED
[01:59:40.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:14:0) after 8m24s
[02:08:04.615] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:14:0)
[02:08:04.633] Build status: STARTED
[02:08:04.633] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:15:0) after 4m21s
[02:12:26.340] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:15:0)
[02:12:26.363] Build status: STARTED
[02:12:26.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:16:0) after 6m51s
[02:19:17.379] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:16:0)
[02:19:17.396] Build status: STARTED
[02:19:17.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:17:0) after 3m11s
[02:22:28.786] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:17:0)
[02:22:28.806] Build status: STARTED
[02:22:28.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:18:0) after 6m29s
[02:28:57.894] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:18:0)
[02:28:57.929] Build status: STARTED
[02:28:57.929] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:19:0) after 1m42s
[02:30:39.983] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:19:0)
[02:30:40.067] Build status: STARTED
[02:30:40.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:20:0) after 4m49s
[02:35:29.092] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:20:0)
[02:35:29.113] Build status: STARTED
[02:35:29.113] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:21:0) after 7m59s
[02:43:28.437] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:21:0)
[02:43:28.475] Build status: STARTED
[02:43:28.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:22:0) after 7m37s
[02:51:05.496] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:22:0)
[02:51:05.518] Build status: STARTED
[02:51:05.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:23:0) after 3m17s
[02:54:22.545] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:23:0)
[02:54:22.610] Build status: STARTED
[02:54:22.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:24:0) after 5m46s
[03:00:08.788] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:24:0)
[03:00:08.866] Build status: STARTED
[03:00:08.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:25:0) after 5m56s
[03:06:04.887] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:25:0)
[03:06:04.909] Build status: STARTED
[03:06:04.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8915427926212471520:26:0) after 2m21s
[03:07:04.697] Received PubSub notification, asking Buildbucket for the build status
[03:07:04.726] Build:
{
"id": "8749997864196881985",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_toolchain.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-04-22T01:19:50.513675985Z",
"startTime": "2024-04-22T01:19:59.011422630Z",
"endTime": "2024-04-22T03:07:04.245008311Z",
"updateTime": "2024-04-22T03:07:04.245008311Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "fb898629a26e4acec59c928ce3ec00a62675d1cc",
"ref": "refs/heads/main"
}
},
"canary": true
}
[03:07:04.726] Invocation finished in 1h47m15.518573064s with status SUCCEEDED