[12:19:30.794] New invocation is queued and will start shortly
[12:19:30.795] Triggered by user:tools-ci-builder@fuchsia-infra.iam.gserviceaccount.com
[12:19:32.045] Starting the invocation (attempt 1)
[12:19:32.070] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/9200cbc71230a86b8a70481b625d93a4fa5ef523"
[12:19:32.070] Reconstructed gitiles commit from tags
[12:19:32.070] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:19:32.072] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:19:32.072] Buildbucket request:
{
"requestId": "8897357625603318784",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_toolchain.core.arm64-debug"
},
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "ca27e789f2d09a9527d2d72d737a0c04893e6591",
"project": "integration",
"ref": "refs/heads/releases/canary"
},
"rust_toolchain": {
"cas_digest": "61781f52955b3d59317a9b0136d1713d17902566db796b4034c64c183310e235/394",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8897357625603318784",
"job": "fuchsia/rust_toolchain.core.arm64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "ca27e789f2d09a9527d2d72d737a0c04893e6591",
"project": "integration",
"ref": "refs/heads/releases/canary"
},
"rust_toolchain": {
"cas_digest": "4bb7ff229fc5d2c3c427a1b1412f39dbb90d62e722888ef100b14ed73d05bdf6/394",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"rust_toolchain": {
"git_repository": "https://fuchsia.googlesource.com/third_party/rust",
"git_revision": "9200cbc71230a86b8a70481b625d93a4fa5ef523"
}
},
"tags": [
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/9200cbc71230a86b8a70481b625d93a4fa5ef523",
"gitiles_ref:refs/heads/main",
"parent_buildername:rust-x64-linux",
"user_agent:recipe"
]
},
"id": "c8225f4a-b1dc-416b-bdc1-339feb75412f",
"title": "rust-x64-linux/0"
},
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "ca27e789f2d09a9527d2d72d737a0c04893e6591",
"project": "integration",
"ref": "refs/heads/releases/canary"
},
"rust_toolchain": {
"cas_digest": "61781f52955b3d59317a9b0136d1713d17902566db796b4034c64c183310e235/394",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"rust_toolchain": {
"git_repository": "https://fuchsia.googlesource.com/third_party/rust",
"git_revision": "9200cbc71230a86b8a70481b625d93a4fa5ef523"
}
},
"tags": [
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/9200cbc71230a86b8a70481b625d93a4fa5ef523",
"gitiles_ref:refs/heads/main",
"parent_buildername:rust-x64-linux-bootstrap",
"user_agent:recipe"
]
},
"id": "dab84641-2735-4599-a07a-4e77bd90d9ce",
"title": "rust-x64-linux-bootstrap/0"
}
]
},
"rust_toolchain": {
"git_repository": "https://fuchsia.googlesource.com/third_party/rust",
"git_revision": "9200cbc71230a86b8a70481b625d93a4fa5ef523"
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "9200cbc71230a86b8a70481b625d93a4fa5ef523",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8897357625603318784"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/rust_toolchain.core.arm64-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"
}
}
[12:19:32.575] Scheduled build:
{
"id": "8731927563533474177",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_toolchain.core.arm64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-07T12:19:32.146109306Z",
"updateTime": "2024-11-07T12:19:32.146109306Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "9200cbc71230a86b8a70481b625d93a4fa5ef523",
"ref": "refs/heads/main"
}
}
}
[12:19:32.575] Task URL: https://cr-buildbucket.appspot.com/build/8731927563533474177
[12:19:32.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:2:0) after 6m48s
[12:19:33.531] Received PubSub notification, asking Buildbucket for the build status
[12:19:33.560] Build status: SCHEDULED
[12:19:39.321] Received PubSub notification, asking Buildbucket for the build status
[12:19:39.351] Build status: STARTED
[12:26:20.589] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:2:0)
[12:26:20.611] Build status: STARTED
[12:26:20.611] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:5:0) after 4m49s
[12:31:09.676] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:5:0)
[12:31:09.707] Build status: STARTED
[12:31:09.707] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:6:0) after 9m56s
[12:41:05.775] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:6:0)
[12:41:05.953] Build status: STARTED
[12:41:05.953] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:7:0) after 5m32s
[12:46:38.077] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:7:0)
[12:46:38.098] Build status: STARTED
[12:46:38.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:8:0) after 2m57s
[12:49:35.144] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:8:0)
[12:49:35.165] Build status: STARTED
[12:49:35.165] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:9:0) after 9m49s
[12:59:24.326] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:9:0)
[12:59:24.344] Build status: STARTED
[12:59:24.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:10:0) after 4m45s
[13:04:09.996] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:10:0)
[13:04:10.023] Build status: STARTED
[13:04:10.023] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:11:0) after 3m48s
[13:07:58.371] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:11:0)
[13:07:58.389] Build status: STARTED
[13:07:58.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:12:0) after 6m54s
[13:14:52.472] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:12:0)
[13:14:52.496] Build status: STARTED
[13:14:52.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:13:0) after 9m4s
[13:23:56.510] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:13:0)
[13:23:56.562] Build status: STARTED
[13:23:56.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:14:0) after 2m10s
[13:26:06.663] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:14:0)
[13:26:06.687] Build status: STARTED
[13:26:06.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:15:0) after 1m12s
[13:27:18.782] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:15:0)
[13:27:18.821] Build status: STARTED
[13:27:18.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:16:0) after 6m8s
[13:33:26.929] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:16:0)
[13:33:26.963] Build status: STARTED
[13:33:26.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:17:0) after 6m58s
[13:40:24.972] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:17:0)
[13:40:24.992] Build status: STARTED
[13:40:24.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:18:0) after 4m59s
[13:45:24.006] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:18:0)
[13:45:24.026] Build status: STARTED
[13:45:24.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:19:0) after 6m2s
[13:51:26.042] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:19:0)
[13:51:26.065] Build status: STARTED
[13:51:26.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:20:0) after 1m10s
[13:52:36.084] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:20:0)
[13:52:36.248] Build status: STARTED
[13:52:36.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:21:0) after 8m3s
[14:00:39.299] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:21:0)
[14:00:39.328] Build status: STARTED
[14:00:39.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:22:0) after 6m45s
[14:07:24.468] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:22:0)
[14:07:24.489] Build status: STARTED
[14:07:24.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:23:0) after 5m23s
[14:12:47.990] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:23:0)
[14:12:48.009] Build status: STARTED
[14:12:48.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:24:0) after 9m36s
[14:22:24.028] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:24:0)
[14:22:24.055] Build status: STARTED
[14:22:24.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:25:0) after 5m32s
[14:27:56.071] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:25:0)
[14:27:56.089] Build status: STARTED
[14:27:56.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.arm64-debug:8897357625603318784:26:0) after 4m29s
[14:29:17.538] Received PubSub notification, asking Buildbucket for the build status
[14:29:17.555] Build:
{
"id": "8731927563533474177",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_toolchain.core.arm64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-07T12:19:32.146109306Z",
"startTime": "2024-11-07T12:19:39.137690144Z",
"endTime": "2024-11-07T14:29:16.988660835Z",
"updateTime": "2024-11-07T14:29:16.988660835Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "9200cbc71230a86b8a70481b625d93a4fa5ef523",
"ref": "refs/heads/main"
}
}
}
[14:29:17.555] Invocation finished in 2h9m46.767231221s with status SUCCEEDED