[21:35:32.177] New invocation is queued and will start shortly
[21:35:33.241] Starting the invocation (attempt 1)
[21:35:33.276] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/36a6373f3d0f479c740919098d24b93019642fca"
[21:35:33.276] Popped gitiles commit info from properties and tags
[21:35:33.276] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:35:33.276] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:35:33.276] Buildbucket request:
{
"requestId": "8902577267716484784",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8902577267716484784",
"job": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "9e339fae43dc8640c0a15aefd730c69ac520716c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@9e339fae43dc8640c0a15aefd730c69ac520716c",
"title": "9e339fae43dc8640c0a15aefd730c69ac520716c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/9e339fae43dc8640c0a15aefd730c69ac520716c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "bfc061dce8919a23bba3edb9e057f44140697e6c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@bfc061dce8919a23bba3edb9e057f44140697e6c",
"title": "bfc061dce8919a23bba3edb9e057f44140697e6c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/bfc061dce8919a23bba3edb9e057f44140697e6c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "dac85a4775231e6afd2f150711f62add2fc7032e"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@dac85a4775231e6afd2f150711f62add2fc7032e",
"title": "dac85a4775231e6afd2f150711f62add2fc7032e",
"url": "https://fuchsia.googlesource.com/fuchsia/+/dac85a4775231e6afd2f150711f62add2fc7032e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "501abceb108f26cbe869b30f12ab4007be890fda"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@501abceb108f26cbe869b30f12ab4007be890fda",
"title": "501abceb108f26cbe869b30f12ab4007be890fda",
"url": "https://fuchsia.googlesource.com/fuchsia/+/501abceb108f26cbe869b30f12ab4007be890fda"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "77ecf7da1ea2414631af4ceba9c6bda6f2d3641c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@77ecf7da1ea2414631af4ceba9c6bda6f2d3641c",
"title": "77ecf7da1ea2414631af4ceba9c6bda6f2d3641c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/77ecf7da1ea2414631af4ceba9c6bda6f2d3641c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "3539d4c72c8e6013a047043dab901ce8686040f6"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@3539d4c72c8e6013a047043dab901ce8686040f6",
"title": "3539d4c72c8e6013a047043dab901ce8686040f6",
"url": "https://fuchsia.googlesource.com/fuchsia/+/3539d4c72c8e6013a047043dab901ce8686040f6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a3cdcf09e92f7f57df1d24af5b4fe9df6e5857d9"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a3cdcf09e92f7f57df1d24af5b4fe9df6e5857d9",
"title": "a3cdcf09e92f7f57df1d24af5b4fe9df6e5857d9",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a3cdcf09e92f7f57df1d24af5b4fe9df6e5857d9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "7052e6d90c4bc5175f435a9e4dd437f4bde7c55b"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@7052e6d90c4bc5175f435a9e4dd437f4bde7c55b",
"title": "7052e6d90c4bc5175f435a9e4dd437f4bde7c55b",
"url": "https://fuchsia.googlesource.com/fuchsia/+/7052e6d90c4bc5175f435a9e4dd437f4bde7c55b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "007e7554d0dcda8228e36b3e94e90d7a102d60cf"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@007e7554d0dcda8228e36b3e94e90d7a102d60cf",
"title": "007e7554d0dcda8228e36b3e94e90d7a102d60cf",
"url": "https://fuchsia.googlesource.com/fuchsia/+/007e7554d0dcda8228e36b3e94e90d7a102d60cf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "eb31146cf8fb2751ef06d0dac91447fc37dec13f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@eb31146cf8fb2751ef06d0dac91447fc37dec13f",
"title": "eb31146cf8fb2751ef06d0dac91447fc37dec13f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/eb31146cf8fb2751ef06d0dac91447fc37dec13f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "fe08d56aab54b64a683e2fd9bb2711908e805f25"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@fe08d56aab54b64a683e2fd9bb2711908e805f25",
"title": "fe08d56aab54b64a683e2fd9bb2711908e805f25",
"url": "https://fuchsia.googlesource.com/fuchsia/+/fe08d56aab54b64a683e2fd9bb2711908e805f25"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "0f7ba06eaffefa1f263ead4d385ab3dcaaf26720"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@0f7ba06eaffefa1f263ead4d385ab3dcaaf26720",
"title": "0f7ba06eaffefa1f263ead4d385ab3dcaaf26720",
"url": "https://fuchsia.googlesource.com/fuchsia/+/0f7ba06eaffefa1f263ead4d385ab3dcaaf26720"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "e9d549e93f508ac168b9acbc8b7317b94ede9643"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@e9d549e93f508ac168b9acbc8b7317b94ede9643",
"title": "e9d549e93f508ac168b9acbc8b7317b94ede9643",
"url": "https://fuchsia.googlesource.com/fuchsia/+/e9d549e93f508ac168b9acbc8b7317b94ede9643"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "36a6373f3d0f479c740919098d24b93019642fca"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@36a6373f3d0f479c740919098d24b93019642fca",
"title": "36a6373f3d0f479c740919098d24b93019642fca",
"url": "https://fuchsia.googlesource.com/fuchsia/+/36a6373f3d0f479c740919098d24b93019642fca"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "36a6373f3d0f479c740919098d24b93019642fca",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8902577267716484784"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:35:33.961] Scheduled build:
{
"id": "8737147205830687457",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-10T21:35:33.346844435Z",
"updateTime": "2024-09-10T21:35:33.346844435Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "36a6373f3d0f479c740919098d24b93019642fca",
"ref": "refs/heads/main"
}
}
}
[21:35:33.961] Task URL: https://cr-buildbucket.appspot.com/build/8737147205830687457
[21:35:33.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:2:0) after 8m55s
[21:35:55.050] Received PubSub notification, asking Buildbucket for the build status
[21:35:55.071] Build status: STARTED
[21:36:09.574] Received PubSub notification, asking Buildbucket for the build status
[21:36:09.623] Build status: STARTED
[21:44:28.979] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:2:0)
[21:44:29.012] Build status: STARTED
[21:44:29.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:5:0) after 1m42s
[21:46:11.039] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:5:0)
[21:46:11.133] Build status: STARTED
[21:46:11.133] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:6:0) after 9m40s
[21:55:51.106] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:6:0)
[21:55:51.169] Build status: STARTED
[21:55:51.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:7:0) after 7m24s
[22:03:15.237] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:7:0)
[22:03:15.258] Build status: STARTED
[22:03:15.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:8:0) after 7m27s
[22:10:42.279] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:8:0)
[22:10:42.302] Build status: STARTED
[22:10:42.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:9:0) after 9m56s
[22:20:38.372] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:9:0)
[22:20:38.389] Build status: STARTED
[22:20:38.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:10:0) after 8m20s
[22:28:58.373] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:10:0)
[22:28:58.394] Build status: STARTED
[22:28:58.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:11:0) after 9m55s
[22:38:53.484] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:11:0)
[22:38:53.508] Build status: STARTED
[22:38:53.508] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:12:0) after 1m28s
[22:40:21.549] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:12:0)
[22:40:21.568] Build status: STARTED
[22:40:21.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:13:0) after 1m43s
[22:42:04.670] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:13:0)
[22:42:04.688] Build status: STARTED
[22:42:04.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:14:0) after 1m10s
[22:43:14.680] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:14:0)
[22:43:14.697] Build status: STARTED
[22:43:14.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:15:0) after 7m44s
[22:50:58.717] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:15:0)
[22:50:58.733] Build status: STARTED
[22:50:58.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:16:0) after 7m20s
[22:58:18.758] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:16:0)
[22:58:18.780] Build status: STARTED
[22:58:18.780] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:17:0) after 3m28s
[23:01:46.797] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:17:0)
[23:01:46.822] Build status: STARTED
[23:01:46.822] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:18:0) after 4m18s
[23:06:04.838] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:18:0)
[23:06:04.857] Build status: STARTED
[23:06:04.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902577267716484784:19:0) after 9m55s
[23:10:46.957] Received PubSub notification, asking Buildbucket for the build status
[23:10:46.977] Build:
{
"id": "8737147205830687457",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-10T21:35:33.346844435Z",
"startTime": "2024-09-10T21:35:46.658988062Z",
"endTime": "2024-09-10T23:10:25.185007767Z",
"updateTime": "2024-09-10T23:10:25.185007767Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "36a6373f3d0f479c740919098d24b93019642fca",
"ref": "refs/heads/main"
}
}
}
[23:10:46.977] Invocation finished in 1h35m14.812597552s with status SUCCEEDED