[02:23:12.756] New invocation is queued and will start shortly
[02:23:13.902] Starting the invocation (attempt 1)
[02:23:13.931] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/cd674d61790607dfb6faa9d754bd3adfa13aea7c"
[02:23:13.931] Popped gitiles commit info from properties and tags
[02:23:13.931] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:23:13.931] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:23:13.932] Buildbucket request:
{
"requestId": "8931821988832524816",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust-x64-windows"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8931821988832524816",
"job": "fuchsia/rust-x64-windows-49def413",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "a8e56d0b0bab2b6ad1151d97ebb6e246a173829f"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@a8e56d0b0bab2b6ad1151d97ebb6e246a173829f",
"title": "a8e56d0b0bab2b6ad1151d97ebb6e246a173829f",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/a8e56d0b0bab2b6ad1151d97ebb6e246a173829f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "8d535070a2e641889c9e51c0d093c01124bef5b0"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@8d535070a2e641889c9e51c0d093c01124bef5b0",
"title": "8d535070a2e641889c9e51c0d093c01124bef5b0",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/8d535070a2e641889c9e51c0d093c01124bef5b0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "90e6d2995581b9f03be52ed7fa92fa6a5b981294"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@90e6d2995581b9f03be52ed7fa92fa6a5b981294",
"title": "90e6d2995581b9f03be52ed7fa92fa6a5b981294",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/90e6d2995581b9f03be52ed7fa92fa6a5b981294"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "4bedd7de6e96a008121c54f34baece868b70b083"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@4bedd7de6e96a008121c54f34baece868b70b083",
"title": "4bedd7de6e96a008121c54f34baece868b70b083",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/4bedd7de6e96a008121c54f34baece868b70b083"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "252c64722f034f14db69d68acd88b0976bd6676d"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@252c64722f034f14db69d68acd88b0976bd6676d",
"title": "252c64722f034f14db69d68acd88b0976bd6676d",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/252c64722f034f14db69d68acd88b0976bd6676d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "f038882fc015dcbd534cc9c654447c97b6e78389"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@f038882fc015dcbd534cc9c654447c97b6e78389",
"title": "f038882fc015dcbd534cc9c654447c97b6e78389",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/f038882fc015dcbd534cc9c654447c97b6e78389"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "c9c0c0cbcabb10529f0284254918b04018d80135"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@c9c0c0cbcabb10529f0284254918b04018d80135",
"title": "c9c0c0cbcabb10529f0284254918b04018d80135",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/c9c0c0cbcabb10529f0284254918b04018d80135"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "547af00019835556de2a5509fabc5bc880e6faca"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@547af00019835556de2a5509fabc5bc880e6faca",
"title": "547af00019835556de2a5509fabc5bc880e6faca",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/547af00019835556de2a5509fabc5bc880e6faca"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "7ae1851803b6adb9c213f6051e705d2f7172ec92"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@7ae1851803b6adb9c213f6051e705d2f7172ec92",
"title": "7ae1851803b6adb9c213f6051e705d2f7172ec92",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/7ae1851803b6adb9c213f6051e705d2f7172ec92"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "abb723dea21669ca9b0a1d4335ec20c2c30846af"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@abb723dea21669ca9b0a1d4335ec20c2c30846af",
"title": "abb723dea21669ca9b0a1d4335ec20c2c30846af",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/abb723dea21669ca9b0a1d4335ec20c2c30846af"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "c8f33ec35f7dccf9890e9f8438c221ac74c1e07d"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@c8f33ec35f7dccf9890e9f8438c221ac74c1e07d",
"title": "c8f33ec35f7dccf9890e9f8438c221ac74c1e07d",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/c8f33ec35f7dccf9890e9f8438c221ac74c1e07d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "cd674d61790607dfb6faa9d754bd3adfa13aea7c"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@cd674d61790607dfb6faa9d754bd3adfa13aea7c",
"title": "cd674d61790607dfb6faa9d754bd3adfa13aea7c",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/cd674d61790607dfb6faa9d754bd3adfa13aea7c"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "cd674d61790607dfb6faa9d754bd3adfa13aea7c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8931821988832524816"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/rust-x64-windows-49def413"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:23:14.419] Scheduled build:
{
"id": "8766391926876053009",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust-x64-windows"
},
"createdBy": "project:fuchsia",
"createTime": "2023-10-24T02:23:13.995937061Z",
"updateTime": "2023-10-24T02:23:13.995937061Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "cd674d61790607dfb6faa9d754bd3adfa13aea7c",
"ref": "refs/heads/main"
}
}
}
[02:23:14.419] Task URL: https://cr-buildbucket.appspot.com/build/8766391926876053009
[02:23:14.419] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust-x64-windows-49def413:8931821988832524816:2:0) after 6m7s
[02:23:15.289] Received PubSub notification, asking Buildbucket for the build status
[02:23:15.313] Build status: STARTED
[02:23:15.777] Received PubSub notification, asking Buildbucket for the build status
[02:23:15.806] Build status: STARTED
[02:29:21.451] Handling timer "check-buildbucket-build-status" (fuchsia/rust-x64-windows-49def413:8931821988832524816:2:0)
[02:29:21.680] Build status: STARTED
[02:29:21.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust-x64-windows-49def413:8931821988832524816:5:0) after 2m59s
[02:32:20.957] Handling timer "check-buildbucket-build-status" (fuchsia/rust-x64-windows-49def413:8931821988832524816:5:0)
[02:32:20.999] Build status: STARTED
[02:32:20.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust-x64-windows-49def413:8931821988832524816:6:0) after 5m14s
[02:37:35.094] Handling timer "check-buildbucket-build-status" (fuchsia/rust-x64-windows-49def413:8931821988832524816:6:0)
[02:37:35.115] Build status: STARTED
[02:37:35.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust-x64-windows-49def413:8931821988832524816:7:0) after 5m56s
[02:43:31.223] Handling timer "check-buildbucket-build-status" (fuchsia/rust-x64-windows-49def413:8931821988832524816:7:0)
[02:43:31.282] Build status: STARTED
[02:43:31.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust-x64-windows-49def413:8931821988832524816:8:0) after 2m6s
[02:45:37.291] Handling timer "check-buildbucket-build-status" (fuchsia/rust-x64-windows-49def413:8931821988832524816:8:0)
[02:45:37.388] Build status: STARTED
[02:45:37.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust-x64-windows-49def413:8931821988832524816:9:0) after 8m30s
[02:54:07.679] Handling timer "check-buildbucket-build-status" (fuchsia/rust-x64-windows-49def413:8931821988832524816:9:0)
[02:54:07.698] Build status: STARTED
[02:54:07.698] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust-x64-windows-49def413:8931821988832524816:10:0) after 6m54s
[03:01:01.817] Handling timer "check-buildbucket-build-status" (fuchsia/rust-x64-windows-49def413:8931821988832524816:10:0)
[03:01:01.980] Build status: STARTED
[03:01:01.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust-x64-windows-49def413:8931821988832524816:11:0) after 1m49s
[03:02:18.794] Received PubSub notification, asking Buildbucket for the build status
[03:02:18.821] Build:
{
"id": "8766391926876053009",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust-x64-windows"
},
"createdBy": "project:fuchsia",
"createTime": "2023-10-24T02:23:13.995937061Z",
"startTime": "2023-10-24T02:23:14.769910Z",
"endTime": "2023-10-24T03:02:18.558196373Z",
"updateTime": "2023-10-24T03:02:18.558196373Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "cd674d61790607dfb6faa9d754bd3adfa13aea7c",
"ref": "refs/heads/main"
}
}
}
[03:02:18.821] Invocation finished in 39m6.075777826s with status SUCCEEDED