[21:22:55.401] New invocation is queued and will start shortly
[21:22:56.514] Starting the invocation (attempt 1)
[21:22:56.545] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/10505a1cc9b16e0c871742c37561c457db888232"
[21:22:56.545] Popped gitiles commit info from properties and tags
[21:22:56.545] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:22:56.545] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:22:56.546] Buildbucket request:
{
"requestId": "8914808651709712928",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust-x64-mac"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8914808651709712928",
"job": "fuchsia/rust-x64-mac-49def413",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "607497d57caf28c3d06adfdd5bca826e9c81205b"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@607497d57caf28c3d06adfdd5bca826e9c81205b",
"title": "607497d57caf28c3d06adfdd5bca826e9c81205b",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/607497d57caf28c3d06adfdd5bca826e9c81205b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "006c94cfa1a4658318f237d87afb2a4434d42b39"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@006c94cfa1a4658318f237d87afb2a4434d42b39",
"title": "006c94cfa1a4658318f237d87afb2a4434d42b39",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/006c94cfa1a4658318f237d87afb2a4434d42b39"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "f5b3b400f7c5f85d3e0a821d83f41b2b71985f9b"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@f5b3b400f7c5f85d3e0a821d83f41b2b71985f9b",
"title": "f5b3b400f7c5f85d3e0a821d83f41b2b71985f9b",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/f5b3b400f7c5f85d3e0a821d83f41b2b71985f9b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "f780913c722a78ab87e3fea10af4b75c0e4c1b66"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@f780913c722a78ab87e3fea10af4b75c0e4c1b66",
"title": "f780913c722a78ab87e3fea10af4b75c0e4c1b66",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/f780913c722a78ab87e3fea10af4b75c0e4c1b66"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "5a3509c2f47758cc714381bc2f2fa68583bd5a5a"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@5a3509c2f47758cc714381bc2f2fa68583bd5a5a",
"title": "5a3509c2f47758cc714381bc2f2fa68583bd5a5a",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/5a3509c2f47758cc714381bc2f2fa68583bd5a5a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "1b567de50abdb5a93f1e9539c0ed0261c249fa55"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@1b567de50abdb5a93f1e9539c0ed0261c249fa55",
"title": "1b567de50abdb5a93f1e9539c0ed0261c249fa55",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/1b567de50abdb5a93f1e9539c0ed0261c249fa55"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "6edb721a9d56b0dfed2fe26f4b0698c30509702f"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@6edb721a9d56b0dfed2fe26f4b0698c30509702f",
"title": "6edb721a9d56b0dfed2fe26f4b0698c30509702f",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/6edb721a9d56b0dfed2fe26f4b0698c30509702f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "10505a1cc9b16e0c871742c37561c457db888232"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@10505a1cc9b16e0c871742c37561c457db888232",
"title": "10505a1cc9b16e0c871742c37561c457db888232",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/10505a1cc9b16e0c871742c37561c457db888232"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "10505a1cc9b16e0c871742c37561c457db888232",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8914808651709712928"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/rust-x64-mac-49def413"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:22:57.081] Scheduled build:
{
"id": "8749378589793947985",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust-x64-mac"
},
"createdBy": "project:fuchsia",
"createTime": "2024-04-28T21:22:56.606454397Z",
"updateTime": "2024-04-28T21:22:56.606454397Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "10505a1cc9b16e0c871742c37561c457db888232",
"ref": "refs/heads/main"
}
}
}
[21:22:57.081] Task URL: https://cr-buildbucket.appspot.com/build/8749378589793947985
[21:22:57.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust-x64-mac-49def413:8914808651709712928:2:0) after 2m27s
[21:22:57.932] Received PubSub notification, asking Buildbucket for the build status
[21:22:58.070] Build status: SCHEDULED
[21:23:01.689] Received PubSub notification, asking Buildbucket for the build status
[21:23:01.711] Build status: STARTED
[21:25:24.130] Handling timer "check-buildbucket-build-status" (fuchsia/rust-x64-mac-49def413:8914808651709712928:2:0)
[21:25:24.149] Build status: STARTED
[21:25:24.150] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust-x64-mac-49def413:8914808651709712928:5:0) after 8m52s
[21:34:16.366] Handling timer "check-buildbucket-build-status" (fuchsia/rust-x64-mac-49def413:8914808651709712928:5:0)
[21:34:16.400] Build status: STARTED
[21:34:16.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust-x64-mac-49def413:8914808651709712928:6:0) after 1m42s
[21:35:58.425] Handling timer "check-buildbucket-build-status" (fuchsia/rust-x64-mac-49def413:8914808651709712928:6:0)
[21:35:58.864] Build status: STARTED
[21:35:58.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust-x64-mac-49def413:8914808651709712928:7:0) after 9m14s
[21:45:13.999] Handling timer "check-buildbucket-build-status" (fuchsia/rust-x64-mac-49def413:8914808651709712928:7:0)
[21:45:14.020] Build status: STARTED
[21:45:14.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust-x64-mac-49def413:8914808651709712928:8:0) after 6m3s
[21:51:17.037] Handling timer "check-buildbucket-build-status" (fuchsia/rust-x64-mac-49def413:8914808651709712928:8:0)
[21:51:17.057] Build status: STARTED
[21:51:17.057] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust-x64-mac-49def413:8914808651709712928:9:0) after 9m35s
[22:00:52.182] Handling timer "check-buildbucket-build-status" (fuchsia/rust-x64-mac-49def413:8914808651709712928:9:0)
[22:00:52.200] Build status: STARTED
[22:00:52.200] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust-x64-mac-49def413:8914808651709712928:10:0) after 4m12s
[22:01:11.881] Received PubSub notification, asking Buildbucket for the build status
[22:01:11.906] Build:
{
"id": "8749378589793947985",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust-x64-mac"
},
"createdBy": "project:fuchsia",
"createTime": "2024-04-28T21:22:56.606454397Z",
"startTime": "2024-04-28T21:23:01.388698520Z",
"endTime": "2024-04-28T22:01:11.590402031Z",
"updateTime": "2024-04-28T22:01:11.590402031Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "10505a1cc9b16e0c871742c37561c457db888232",
"ref": "refs/heads/main"
}
}
}
[22:01:11.906] Invocation finished in 38m16.510325711s with status SUCCEEDED