[09:52:52.715] New invocation is queued and will start shortly
[09:52:54.292] Starting the invocation (attempt 1)
[09:52:54.319] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/170d6cb845c8c3f0dcec5cdd4210df9ecf990244"
[09:52:54.319] Popped gitiles commit info from properties and tags
[09:52:54.319] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:52:54.319] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:52:54.319] Buildbucket request:
{
"requestId": "8902077893312751808",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_test-x64-linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8902077893312751808",
"job": "fuchsia/rust_test-x64-linux-49def413",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "56fb89acee048c80b595f42cdcb749ed39a43e10"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@56fb89acee048c80b595f42cdcb749ed39a43e10",
"title": "56fb89acee048c80b595f42cdcb749ed39a43e10",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/56fb89acee048c80b595f42cdcb749ed39a43e10"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "d9e560cd542dc8bb3b70a4270d85b7163a14f743"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@d9e560cd542dc8bb3b70a4270d85b7163a14f743",
"title": "d9e560cd542dc8bb3b70a4270d85b7163a14f743",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/d9e560cd542dc8bb3b70a4270d85b7163a14f743"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "7223fd80859fd7987e5b41c1c3fba20d09cfab48"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@7223fd80859fd7987e5b41c1c3fba20d09cfab48",
"title": "7223fd80859fd7987e5b41c1c3fba20d09cfab48",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/7223fd80859fd7987e5b41c1c3fba20d09cfab48"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "ab8c2025279077fcfb0992c455bb8c173b2d9e53"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@ab8c2025279077fcfb0992c455bb8c173b2d9e53",
"title": "ab8c2025279077fcfb0992c455bb8c173b2d9e53",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/ab8c2025279077fcfb0992c455bb8c173b2d9e53"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "1f48a67a257eec3d21204a89fc74f00bcf1b2a5d"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@1f48a67a257eec3d21204a89fc74f00bcf1b2a5d",
"title": "1f48a67a257eec3d21204a89fc74f00bcf1b2a5d",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/1f48a67a257eec3d21204a89fc74f00bcf1b2a5d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "0151cbe6e8ca1027f5482c98a1b93d70fbd9a50b"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@0151cbe6e8ca1027f5482c98a1b93d70fbd9a50b",
"title": "0151cbe6e8ca1027f5482c98a1b93d70fbd9a50b",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/0151cbe6e8ca1027f5482c98a1b93d70fbd9a50b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "68758c05604f0ae4dfbe25f61e70f1a3db083c29"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@68758c05604f0ae4dfbe25f61e70f1a3db083c29",
"title": "68758c05604f0ae4dfbe25f61e70f1a3db083c29",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/68758c05604f0ae4dfbe25f61e70f1a3db083c29"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "9025fe9e649973aded1cf5e56da58aa65543c75b"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@9025fe9e649973aded1cf5e56da58aa65543c75b",
"title": "9025fe9e649973aded1cf5e56da58aa65543c75b",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/9025fe9e649973aded1cf5e56da58aa65543c75b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "7142e0db604c44e6c251553cafd54247682d7e21"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@7142e0db604c44e6c251553cafd54247682d7e21",
"title": "7142e0db604c44e6c251553cafd54247682d7e21",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/7142e0db604c44e6c251553cafd54247682d7e21"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "f64d1c1f8fcf79eb486eef2c2fe04cd88dd681bd"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@f64d1c1f8fcf79eb486eef2c2fe04cd88dd681bd",
"title": "f64d1c1f8fcf79eb486eef2c2fe04cd88dd681bd",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/f64d1c1f8fcf79eb486eef2c2fe04cd88dd681bd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "170d6cb845c8c3f0dcec5cdd4210df9ecf990244"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@170d6cb845c8c3f0dcec5cdd4210df9ecf990244",
"title": "170d6cb845c8c3f0dcec5cdd4210df9ecf990244",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/170d6cb845c8c3f0dcec5cdd4210df9ecf990244"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "170d6cb845c8c3f0dcec5cdd4210df9ecf990244",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8902077893312751808"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/rust_test-x64-linux-49def413"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[09:52:54.834] Scheduled build:
{
"id": "8736647830901173425",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_test-x64-linux"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-16T09:52:54.391165052Z",
"updateTime": "2024-09-16T09:52:54.391165052Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "170d6cb845c8c3f0dcec5cdd4210df9ecf990244",
"ref": "refs/heads/main"
}
},
"canary": true
}
[09:52:54.834] Task URL: https://cr-buildbucket.appspot.com/build/8736647830901173425
[09:52:54.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8902077893312751808:2:0) after 8m34s
[09:52:55.768] Received PubSub notification, asking Buildbucket for the build status
[09:52:55.791] Build status: SCHEDULED
[09:52:58.640] Received PubSub notification, asking Buildbucket for the build status
[09:52:58.659] Build status: STARTED
[10:01:28.923] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8902077893312751808:2:0)
[10:01:28.949] Build status: STARTED
[10:01:28.949] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8902077893312751808:5:0) after 5m17s
[10:06:46.066] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8902077893312751808:5:0)
[10:06:46.093] Build status: STARTED
[10:06:46.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8902077893312751808:6:0) after 8m20s
[10:15:06.091] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8902077893312751808:6:0)
[10:15:06.113] Build status: STARTED
[10:15:06.113] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8902077893312751808:7:0) after 4m5s
[10:19:11.191] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8902077893312751808:7:0)
[10:19:11.213] Build status: STARTED
[10:19:11.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8902077893312751808:8:0) after 3m59s
[10:23:10.371] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8902077893312751808:8:0)
[10:23:10.390] Build status: STARTED
[10:23:10.390] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8902077893312751808:9:0) after 2m19s
[10:25:29.577] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8902077893312751808:9:0)
[10:25:29.644] Build status: STARTED
[10:25:29.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8902077893312751808:10:0) after 7m3s
[10:32:32.687] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8902077893312751808:10:0)
[10:32:32.708] Build status: STARTED
[10:32:32.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8902077893312751808:11:0) after 7m12s
[10:39:45.025] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8902077893312751808:11:0)
[10:39:45.041] Build status: STARTED
[10:39:45.041] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8902077893312751808:12:0) after 9m32s
[10:46:46.945] Received PubSub notification, asking Buildbucket for the build status
[10:46:47.048] Build:
{
"id": "8736647830901173425",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_test-x64-linux"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-16T09:52:54.391165052Z",
"startTime": "2024-09-16T09:52:58.477285749Z",
"endTime": "2024-09-16T10:46:46.489050615Z",
"updateTime": "2024-09-16T10:46:46.489050615Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "170d6cb845c8c3f0dcec5cdd4210df9ecf990244",
"ref": "refs/heads/main"
}
},
"canary": true
}
[10:46:47.048] Invocation finished in 53m54.340872476s with status FAILED