[07:15:06.806] New invocation is queued and will start shortly
[07:15:06.806] Triggered by user:tools-ci-builder@fuchsia-infra.iam.gserviceaccount.com
[07:15:07.981] Starting the invocation (attempt 1)
[07:15:08.013] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/3148b35f6a6226548929c12da529ab0d1a4b47d5"
[07:15:08.013] Reconstructed gitiles commit from tags
[07:15:08.013] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:15:08.013] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:15:08.013] Buildbucket request:
{
"requestId": "8906617667356227936",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_toolchain.core.x64-debug"
},
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "b101cb686daae9c9c932bde68bb00d53453a3005",
"project": "integration",
"ref": "refs/heads/releases/canary"
},
"rust_toolchain": {
"cas_digest": "a9ce67235ff537330c25f4d75b1f1e2fbf5adc9e53a72e7decdbe3a427e74512/394",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8906617667356227936",
"job": "fuchsia/rust_toolchain.core.x64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "b101cb686daae9c9c932bde68bb00d53453a3005",
"project": "integration",
"ref": "refs/heads/releases/canary"
},
"rust_toolchain": {
"cas_digest": "a9ce67235ff537330c25f4d75b1f1e2fbf5adc9e53a72e7decdbe3a427e74512/394",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"rust_toolchain": {
"git_repository": "https://fuchsia.googlesource.com/third_party/rust",
"git_revision": "3148b35f6a6226548929c12da529ab0d1a4b47d5"
}
},
"tags": [
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/3148b35f6a6226548929c12da529ab0d1a4b47d5",
"gitiles_ref:refs/heads/main",
"parent_buildername:rust-x64-linux",
"user_agent:recipe"
]
},
"id": "d4d71b58-28fe-423e-92fc-a93b57cfae9c",
"title": "rust-x64-linux/0"
}
]
},
"rust_toolchain": {
"git_repository": "https://fuchsia.googlesource.com/third_party/rust",
"git_revision": "3148b35f6a6226548929c12da529ab0d1a4b47d5"
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "3148b35f6a6226548929c12da529ab0d1a4b47d5",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8906617667356227936"
},
{
"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"
}
}
[07:15:08.521] Scheduled build:
{
"id": "8741187605380383681",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_toolchain.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-07-28T07:15:08.068332650Z",
"updateTime": "2024-07-28T07:15:08.068332650Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "3148b35f6a6226548929c12da529ab0d1a4b47d5",
"ref": "refs/heads/main"
}
}
}
[07:15:08.521] Task URL: https://cr-buildbucket.appspot.com/build/8741187605380383681
[07:15:08.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:2:0) after 4m29s
[07:15:09.499] Received PubSub notification, asking Buildbucket for the build status
[07:15:09.520] Build status: SCHEDULED
[07:15:14.202] Received PubSub notification, asking Buildbucket for the build status
[07:15:14.218] Build status: STARTED
[07:19:37.656] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:2:0)
[07:19:37.687] Build status: STARTED
[07:19:37.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:5:0) after 5m13s
[07:24:50.714] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:5:0)
[07:24:50.799] Build status: STARTED
[07:24:50.799] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:6:0) after 6m45s
[07:31:35.814] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:6:0)
[07:31:35.836] Build status: STARTED
[07:31:35.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:7:0) after 1m48s
[07:33:23.933] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:7:0)
[07:33:23.959] Build status: STARTED
[07:33:23.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:8:0) after 7m26s
[07:40:50.798] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:8:0)
[07:40:50.819] Build status: STARTED
[07:40:50.819] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:9:0) after 4m42s
[07:45:32.937] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:9:0)
[07:45:32.966] Build status: STARTED
[07:45:32.966] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:10:0) after 5m10s
[07:50:42.994] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:10:0)
[07:50:43.013] Build status: STARTED
[07:50:43.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:11:0) after 8m45s
[07:59:28.026] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:11:0)
[07:59:28.056] Build status: STARTED
[07:59:28.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:12:0) after 1m54s
[08:01:22.083] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:12:0)
[08:01:22.178] Build status: STARTED
[08:01:22.178] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:13:0) after 3m4s
[08:04:26.204] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:13:0)
[08:04:26.229] Build status: STARTED
[08:04:26.229] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:14:0) after 6m52s
[08:11:18.246] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:14:0)
[08:11:18.264] Build status: STARTED
[08:11:18.264] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:15:0) after 1m25s
[08:12:43.277] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:15:0)
[08:12:43.297] Build status: STARTED
[08:12:43.297] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:16:0) after 3m59s
[08:16:42.403] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:16:0)
[08:16:42.422] Build status: STARTED
[08:16:42.422] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:17:0) after 3m56s
[08:20:38.450] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:17:0)
[08:20:38.469] Build status: STARTED
[08:20:38.469] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:18:0) after 3m10s
[08:23:48.525] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:18:0)
[08:23:48.544] Build status: STARTED
[08:23:48.544] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:19:0) after 3m30s
[08:27:18.568] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:19:0)
[08:27:18.635] Build status: STARTED
[08:27:18.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:20:0) after 1m6s
[08:28:24.651] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:20:0)
[08:28:24.673] Build status: STARTED
[08:28:24.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:21:0) after 4m45s
[08:33:09.698] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:21:0)
[08:33:09.716] Build status: STARTED
[08:33:09.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:22:0) after 1m41s
[08:34:50.831] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:22:0)
[08:34:50.854] Build status: STARTED
[08:34:50.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:23:0) after 3m5s
[08:37:56.126] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:23:0)
[08:37:56.148] Build status: STARTED
[08:37:56.148] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:24:0) after 1m29s
[08:39:25.363] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:24:0)
[08:39:25.419] Build status: STARTED
[08:39:25.419] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:25:0) after 8m31s
[08:47:56.441] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:25:0)
[08:47:56.466] Build status: STARTED
[08:47:56.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:26:0) after 5m51s
[08:53:47.485] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:26:0)
[08:53:47.512] Build status: STARTED
[08:53:47.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:27:0) after 8m12s
[09:01:59.605] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:27:0)
[09:01:59.625] Build status: STARTED
[09:01:59.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:28:0) after 6m46s
[09:08:45.647] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:28:0)
[09:08:45.667] Build status: STARTED
[09:08:45.668] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8906617667356227936:29:0) after 8m0s
[09:16:04.483] Received PubSub notification, asking Buildbucket for the build status
[09:16:04.561] Build:
{
"id": "8741187605380383681",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_toolchain.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-07-28T07:15:08.068332650Z",
"startTime": "2024-07-28T07:15:13.990713372Z",
"endTime": "2024-07-28T09:16:03.643790607Z",
"updateTime": "2024-07-28T09:16:03.643790607Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "3148b35f6a6226548929c12da529ab0d1a4b47d5",
"ref": "refs/heads/main"
}
}
}
[09:16:04.561] Invocation finished in 2h0m57.761088783s with status SUCCEEDED