[15:32:42.306] New invocation is queued and will start shortly
[15:32:43.465] Starting the invocation (attempt 1)
[15:32:43.489] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/e5efc336720901420a8891dcdb67ca0a475dc03c"
[15:32:43.489] Popped gitiles commit info from properties and tags
[15:32:43.489] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:32:43.489] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:32:43.490] Buildbucket request:
{
"requestId": "8864549369186208768",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_test-x64-linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8864549369186208768",
"job": "fuchsia/toolchain.ci-rust_test-x64-linux-49def413",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "948bed2f0ca9e3a0ae718303149d8082ed513654"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@948bed2f0ca9e3a0ae718303149d8082ed513654",
"title": "948bed2f0ca9e3a0ae718303149d8082ed513654",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/948bed2f0ca9e3a0ae718303149d8082ed513654"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "7354d3d9c2ce2c1660e24f5257f9d6ace4a08387"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@7354d3d9c2ce2c1660e24f5257f9d6ace4a08387",
"title": "7354d3d9c2ce2c1660e24f5257f9d6ace4a08387",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/7354d3d9c2ce2c1660e24f5257f9d6ace4a08387"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "1866b3a8cf0917aa1f08eee7b6d5123823b2a5bc"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@1866b3a8cf0917aa1f08eee7b6d5123823b2a5bc",
"title": "1866b3a8cf0917aa1f08eee7b6d5123823b2a5bc",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/1866b3a8cf0917aa1f08eee7b6d5123823b2a5bc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "7be6d6f2e8742cd55a2ce8b6004eb76687c46bee"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@7be6d6f2e8742cd55a2ce8b6004eb76687c46bee",
"title": "7be6d6f2e8742cd55a2ce8b6004eb76687c46bee",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/7be6d6f2e8742cd55a2ce8b6004eb76687c46bee"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "add37c0c25f271771c6760720885f8520bc69bc8"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@add37c0c25f271771c6760720885f8520bc69bc8",
"title": "add37c0c25f271771c6760720885f8520bc69bc8",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/add37c0c25f271771c6760720885f8520bc69bc8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "8b96fbecb6af782c3ef683eb26c330cc452380f8"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@8b96fbecb6af782c3ef683eb26c330cc452380f8",
"title": "8b96fbecb6af782c3ef683eb26c330cc452380f8",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/8b96fbecb6af782c3ef683eb26c330cc452380f8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "a92bae0b1ca3beb7862639a1ce4e9dea49a5c4d7"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@a92bae0b1ca3beb7862639a1ce4e9dea49a5c4d7",
"title": "a92bae0b1ca3beb7862639a1ce4e9dea49a5c4d7",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/a92bae0b1ca3beb7862639a1ce4e9dea49a5c4d7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "5f312079f2782999e07ff25869e1353082bbe240"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@5f312079f2782999e07ff25869e1353082bbe240",
"title": "5f312079f2782999e07ff25869e1353082bbe240",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/5f312079f2782999e07ff25869e1353082bbe240"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "fa9ea6d9188d57e2067882d528190ad59f5dd34c"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@fa9ea6d9188d57e2067882d528190ad59f5dd34c",
"title": "fa9ea6d9188d57e2067882d528190ad59f5dd34c",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/fa9ea6d9188d57e2067882d528190ad59f5dd34c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "b40a20f16df44bb6016dc6b18d0c2e6e96200483"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@b40a20f16df44bb6016dc6b18d0c2e6e96200483",
"title": "b40a20f16df44bb6016dc6b18d0c2e6e96200483",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/b40a20f16df44bb6016dc6b18d0c2e6e96200483"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "07192879f423deb5dfd221a25a8bb7308143ce40"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@07192879f423deb5dfd221a25a8bb7308143ce40",
"title": "07192879f423deb5dfd221a25a8bb7308143ce40",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/07192879f423deb5dfd221a25a8bb7308143ce40"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "4f3816be6f9054e56e2b0b28101e1aa3a20fba94"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@4f3816be6f9054e56e2b0b28101e1aa3a20fba94",
"title": "4f3816be6f9054e56e2b0b28101e1aa3a20fba94",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/4f3816be6f9054e56e2b0b28101e1aa3a20fba94"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "e5efc336720901420a8891dcdb67ca0a475dc03c"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@e5efc336720901420a8891dcdb67ca0a475dc03c",
"title": "e5efc336720901420a8891dcdb67ca0a475dc03c",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/e5efc336720901420a8891dcdb67ca0a475dc03c"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "e5efc336720901420a8891dcdb67ca0a475dc03c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8864549369186208768"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/toolchain.ci-rust_test-x64-linux-49def413"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[15:32:44.011] Scheduled build:
{
"id": "8699119307231410913",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_test-x64-linux"
},
"createdBy": "project:fuchsia",
"createTime": "2025-11-04T15:32:43.547580284Z",
"updateTime": "2025-11-04T15:32:43.547580284Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "e5efc336720901420a8891dcdb67ca0a475dc03c",
"ref": "refs/heads/main"
}
}
}
[15:32:44.011] Task URL: https://cr-buildbucket.appspot.com/build/8699119307231410913
[15:32:44.011] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:2:0) after 3m45s
[15:32:44.888] Received PubSub notification, asking Buildbucket for the build status
[15:32:44.908] Build status: SCHEDULED
[15:32:50.753] Received PubSub notification, asking Buildbucket for the build status
[15:32:50.770] Build status: STARTED
[15:36:29.028] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:2:0)
[15:36:29.051] Build status: STARTED
[15:36:29.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:5:0) after 7m49s
[15:44:18.064] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:5:0)
[15:44:18.100] Build status: STARTED
[15:44:18.100] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:6:0) after 6m27s
[15:50:45.141] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:6:0)
[15:50:45.156] Build status: STARTED
[15:50:45.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:7:0) after 7m49s
[15:58:34.222] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:7:0)
[15:58:34.331] Build status: STARTED
[15:58:34.332] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:8:0) after 3m1s
[16:01:35.405] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:8:0)
[16:01:35.422] Build status: STARTED
[16:01:35.422] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:9:0) after 1m1s
[16:02:36.438] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:9:0)
[16:02:36.471] Build status: STARTED
[16:02:36.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:10:0) after 2m16s
[16:04:52.503] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:10:0)
[16:04:52.553] Build status: STARTED
[16:04:52.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:11:0) after 7m39s
[16:12:31.570] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:11:0)
[16:12:31.586] Build status: STARTED
[16:12:31.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:12:0) after 4m47s
[16:17:18.733] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:12:0)
[16:17:18.749] Build status: STARTED
[16:17:18.749] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:13:0) after 7m8s
[16:24:26.781] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:13:0)
[16:24:26.801] Build status: STARTED
[16:24:26.801] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:14:0) after 7m20s
[16:31:46.838] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:14:0)
[16:31:47.014] Build status: STARTED
[16:31:47.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-rust_test-x64-linux-49def413:8864549369186208768:15:0) after 7m29s
[16:37:46.750] Received PubSub notification, asking Buildbucket for the build status
[16:37:46.771] Build:
{
"id": "8699119307231410913",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_test-x64-linux"
},
"createdBy": "project:fuchsia",
"createTime": "2025-11-04T15:32:43.547580284Z",
"startTime": "2025-11-04T15:32:49.558307920Z",
"endTime": "2025-11-04T16:37:46.417385098Z",
"updateTime": "2025-11-04T16:37:46.417385098Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "e5efc336720901420a8891dcdb67ca0a475dc03c",
"ref": "refs/heads/main"
}
}
}
[16:37:46.771] Invocation finished in 1h5m4.472023426s with status FAILED