[08:40:37.514] New invocation is queued and will start shortly
[08:40:37.514] Triggered by user:tools-ci-builder@fuchsia-infra.iam.gserviceaccount.com
[08:40:38.626] Starting the invocation (attempt 1)
[08:40:38.663] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/d6c8169c186ab16a3404cd0d0866674018e8a19e"
[08:40:38.663] Reconstructed gitiles commit from tags
[08:40:38.663] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:40:38.663] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:40:38.663] Buildbucket request:
{
"requestId": "8903260199662368128",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_toolchain.core.x64-debug"
},
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "f8430d16a1a9640f8b0d9f184892871328fa303b",
"project": "integration",
"ref": "refs/heads/releases/canary"
},
"rust_toolchain": {
"cas_digest": "95375b2b4f0ff13c3ca69cce348097e24ae5aaddc06ddcde0862fb65b90adf24/394",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8903260199662368128",
"job": "fuchsia/rust_toolchain.core.x64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "f8430d16a1a9640f8b0d9f184892871328fa303b",
"project": "integration",
"ref": "refs/heads/releases/canary"
},
"rust_toolchain": {
"cas_digest": "95375b2b4f0ff13c3ca69cce348097e24ae5aaddc06ddcde0862fb65b90adf24/394",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"rust_toolchain": {
"git_repository": "https://fuchsia.googlesource.com/third_party/rust",
"git_revision": "d6c8169c186ab16a3404cd0d0866674018e8a19e"
}
},
"tags": [
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/d6c8169c186ab16a3404cd0d0866674018e8a19e",
"gitiles_ref:refs/heads/main",
"parent_buildername:rust-x64-linux",
"user_agent:recipe"
]
},
"id": "a45eebe1-0c02-4868-b1bf-ad4f101f1311",
"title": "rust-x64-linux/0"
}
]
},
"rust_toolchain": {
"git_repository": "https://fuchsia.googlesource.com/third_party/rust",
"git_revision": "d6c8169c186ab16a3404cd0d0866674018e8a19e"
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "d6c8169c186ab16a3404cd0d0866674018e8a19e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8903260199662368128"
},
{
"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"
}
}
[08:40:39.123] Scheduled build:
{
"id": "8737830137733227953",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_toolchain.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-03T08:40:38.731010932Z",
"updateTime": "2024-09-03T08:40:38.731010932Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "d6c8169c186ab16a3404cd0d0866674018e8a19e",
"ref": "refs/heads/main"
}
},
"canary": true
}
[08:40:39.123] Task URL: https://cr-buildbucket.appspot.com/build/8737830137733227953
[08:40:39.123] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:2:0) after 1m47s
[08:40:40.005] Received PubSub notification, asking Buildbucket for the build status
[08:40:40.033] Build status: SCHEDULED
[08:40:44.764] Received PubSub notification, asking Buildbucket for the build status
[08:40:44.782] Build status: STARTED
[08:42:26.185] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:2:0)
[08:42:26.218] Build status: STARTED
[08:42:26.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:5:0) after 3m22s
[08:45:48.280] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:5:0)
[08:45:48.309] Build status: STARTED
[08:45:48.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:6:0) after 8m7s
[08:53:55.328] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:6:0)
[08:53:55.360] Build status: STARTED
[08:53:55.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:7:0) after 6m44s
[09:00:39.375] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:7:0)
[09:00:39.391] Build status: STARTED
[09:00:39.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:8:0) after 2m36s
[09:03:15.406] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:8:0)
[09:03:15.423] Build status: STARTED
[09:03:15.423] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:9:0) after 3m42s
[09:06:57.506] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:9:0)
[09:06:57.525] Build status: STARTED
[09:06:57.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:10:0) after 1m31s
[09:08:28.532] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:10:0)
[09:08:28.553] Build status: STARTED
[09:08:28.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:11:0) after 4m31s
[09:12:59.569] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:11:0)
[09:12:59.585] Build status: STARTED
[09:12:59.585] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:12:0) after 9m18s
[09:22:17.627] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:12:0)
[09:22:17.673] Build status: STARTED
[09:22:17.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:13:0) after 1m13s
[09:23:30.695] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:13:0)
[09:23:30.723] Build status: STARTED
[09:23:30.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:14:0) after 8m56s
[09:32:26.738] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:14:0)
[09:32:26.756] Build status: STARTED
[09:32:26.756] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:15:0) after 9m47s
[09:42:13.774] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:15:0)
[09:42:13.795] Build status: STARTED
[09:42:13.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:16:0) after 1m36s
[09:43:50.244] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:16:0)
[09:43:50.266] Build status: STARTED
[09:43:50.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:17:0) after 8m55s
[09:52:45.468] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:17:0)
[09:52:45.484] Build status: STARTED
[09:52:45.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:18:0) after 4m13s
[09:56:58.860] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:18:0)
[09:56:58.879] Build status: STARTED
[09:56:58.879] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:19:0) after 7m6s
[10:04:04.894] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:19:0)
[10:04:04.913] Build status: STARTED
[10:04:04.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:20:0) after 4m10s
[10:08:14.934] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:20:0)
[10:08:14.951] Build status: STARTED
[10:08:14.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:21:0) after 1m25s
[10:09:40.044] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:21:0)
[10:09:40.064] Build status: STARTED
[10:09:40.064] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:22:0) after 5m5s
[10:14:45.145] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:22:0)
[10:14:45.170] Build status: STARTED
[10:14:45.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:23:0) after 7m1s
[10:21:46.774] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:23:0)
[10:21:46.818] Build status: STARTED
[10:21:46.819] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:24:0) after 7m0s
[10:28:46.974] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:24:0)
[10:28:46.991] Build status: STARTED
[10:28:46.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:25:0) after 5m22s
[10:34:09.009] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:25:0)
[10:34:09.028] Build status: STARTED
[10:34:09.029] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:26:0) after 6m3s
[10:40:12.219] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:26:0)
[10:40:12.236] Build status: STARTED
[10:40:12.236] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:27:0) after 1m54s
[10:42:06.319] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:27:0)
[10:42:06.336] Build status: STARTED
[10:42:06.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8903260199662368128:28:0) after 2m27s
[10:44:26.516] Received PubSub notification, asking Buildbucket for the build status
[10:44:26.597] Build:
{
"id": "8737830137733227953",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_toolchain.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-03T08:40:38.731010932Z",
"startTime": "2024-09-03T08:40:44.547564929Z",
"endTime": "2024-09-03T10:44:20.264482583Z",
"updateTime": "2024-09-03T10:44:20.264482583Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "d6c8169c186ab16a3404cd0d0866674018e8a19e",
"ref": "refs/heads/main"
}
},
"canary": true
}
[10:44:26.597] Invocation finished in 2h3m49.089678693s with status SUCCEEDED