[19:49:08.822] New invocation is queued and will start shortly
[19:49:10.236] Starting the invocation (attempt 1)
[19:49:10.261] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/652bd76a3c122bfdacc3d67d1b37bbf495c5fbfa"
[19:49:10.261] Popped gitiles commit info from properties and tags
[19:49:10.261] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:49:10.262] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:49:10.262] Buildbucket request:
{
"requestId": "8899775455192574320",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8899775455192574320",
"job": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "2629c35207356c7e4a1b83c7e5b609857d10e20d"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@2629c35207356c7e4a1b83c7e5b609857d10e20d",
"title": "2629c35207356c7e4a1b83c7e5b609857d10e20d",
"url": "https://fuchsia.googlesource.com/fuchsia/+/2629c35207356c7e4a1b83c7e5b609857d10e20d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "082531a2c8e1274c85404bf529d580bf761e69d3"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@082531a2c8e1274c85404bf529d580bf761e69d3",
"title": "082531a2c8e1274c85404bf529d580bf761e69d3",
"url": "https://fuchsia.googlesource.com/fuchsia/+/082531a2c8e1274c85404bf529d580bf761e69d3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a84be761ee1299a86a75f785c338ee2615e0d51e"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a84be761ee1299a86a75f785c338ee2615e0d51e",
"title": "a84be761ee1299a86a75f785c338ee2615e0d51e",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a84be761ee1299a86a75f785c338ee2615e0d51e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "b99ea1406c32157a3b6ba7529914920b6f73bd5c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@b99ea1406c32157a3b6ba7529914920b6f73bd5c",
"title": "b99ea1406c32157a3b6ba7529914920b6f73bd5c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/b99ea1406c32157a3b6ba7529914920b6f73bd5c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "d6575fc9966b210c3e99a9372a33589f9ad14287"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@d6575fc9966b210c3e99a9372a33589f9ad14287",
"title": "d6575fc9966b210c3e99a9372a33589f9ad14287",
"url": "https://fuchsia.googlesource.com/fuchsia/+/d6575fc9966b210c3e99a9372a33589f9ad14287"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "296f0699ec1bc5bee22959bcb50c73f4b9167d5f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@296f0699ec1bc5bee22959bcb50c73f4b9167d5f",
"title": "296f0699ec1bc5bee22959bcb50c73f4b9167d5f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/296f0699ec1bc5bee22959bcb50c73f4b9167d5f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "118d03ca696da5a8606935369f40023b06b59d40"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@118d03ca696da5a8606935369f40023b06b59d40",
"title": "118d03ca696da5a8606935369f40023b06b59d40",
"url": "https://fuchsia.googlesource.com/fuchsia/+/118d03ca696da5a8606935369f40023b06b59d40"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "25c7b18929cbf0da232b08820d169e17c16bccf4"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@25c7b18929cbf0da232b08820d169e17c16bccf4",
"title": "25c7b18929cbf0da232b08820d169e17c16bccf4",
"url": "https://fuchsia.googlesource.com/fuchsia/+/25c7b18929cbf0da232b08820d169e17c16bccf4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "fa3c5dd3fd57437d7771a9a12ebdc698d00bd5db"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@fa3c5dd3fd57437d7771a9a12ebdc698d00bd5db",
"title": "fa3c5dd3fd57437d7771a9a12ebdc698d00bd5db",
"url": "https://fuchsia.googlesource.com/fuchsia/+/fa3c5dd3fd57437d7771a9a12ebdc698d00bd5db"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "2959b7047c62719e25f3cf2661a71665429622e1"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@2959b7047c62719e25f3cf2661a71665429622e1",
"title": "2959b7047c62719e25f3cf2661a71665429622e1",
"url": "https://fuchsia.googlesource.com/fuchsia/+/2959b7047c62719e25f3cf2661a71665429622e1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "1541c0aadf64225755940907aa70f2d0fe08b4cc"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@1541c0aadf64225755940907aa70f2d0fe08b4cc",
"title": "1541c0aadf64225755940907aa70f2d0fe08b4cc",
"url": "https://fuchsia.googlesource.com/fuchsia/+/1541c0aadf64225755940907aa70f2d0fe08b4cc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "022daec094b96fbc63883875bedb90157f1a9e47"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@022daec094b96fbc63883875bedb90157f1a9e47",
"title": "022daec094b96fbc63883875bedb90157f1a9e47",
"url": "https://fuchsia.googlesource.com/fuchsia/+/022daec094b96fbc63883875bedb90157f1a9e47"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "715528af2d2c273337c5d1f119ae16851b59de3d"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@715528af2d2c273337c5d1f119ae16851b59de3d",
"title": "715528af2d2c273337c5d1f119ae16851b59de3d",
"url": "https://fuchsia.googlesource.com/fuchsia/+/715528af2d2c273337c5d1f119ae16851b59de3d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6ed135b57624fd6e8fc9a1ff15ae5ae8cd1e499a"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6ed135b57624fd6e8fc9a1ff15ae5ae8cd1e499a",
"title": "6ed135b57624fd6e8fc9a1ff15ae5ae8cd1e499a",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6ed135b57624fd6e8fc9a1ff15ae5ae8cd1e499a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "f89ff9c89bae0347468eac20f2478b9d13c53425"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@f89ff9c89bae0347468eac20f2478b9d13c53425",
"title": "f89ff9c89bae0347468eac20f2478b9d13c53425",
"url": "https://fuchsia.googlesource.com/fuchsia/+/f89ff9c89bae0347468eac20f2478b9d13c53425"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "652bd76a3c122bfdacc3d67d1b37bbf495c5fbfa"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@652bd76a3c122bfdacc3d67d1b37bbf495c5fbfa",
"title": "652bd76a3c122bfdacc3d67d1b37bbf495c5fbfa",
"url": "https://fuchsia.googlesource.com/fuchsia/+/652bd76a3c122bfdacc3d67d1b37bbf495c5fbfa"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "652bd76a3c122bfdacc3d67d1b37bbf495c5fbfa",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8899775455192574320"
},
{
"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"
}
}
[19:49:10.767] Scheduled build:
{
"id": "8734345392958229329",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-11T19:49:10.322107694Z",
"updateTime": "2024-10-11T19:49:10.322107694Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "652bd76a3c122bfdacc3d67d1b37bbf495c5fbfa",
"ref": "refs/heads/main"
}
}
}
[19:49:10.767] Task URL: https://cr-buildbucket.appspot.com/build/8734345392958229329
[19:49:10.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:2:0) after 5m27s
[19:49:11.795] Received PubSub notification, asking Buildbucket for the build status
[19:49:11.829] Build status: SCHEDULED
[19:49:17.013] Received PubSub notification, asking Buildbucket for the build status
[19:49:17.056] Build status: STARTED
[19:54:37.841] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:2:0)
[19:54:37.926] Build status: STARTED
[19:54:37.927] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:5:0) after 2m19s
[19:56:56.947] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:5:0)
[19:56:56.976] Build status: STARTED
[19:56:56.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:6:0) after 7m37s
[20:04:33.991] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:6:0)
[20:04:34.018] Build status: STARTED
[20:04:34.018] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:7:0) after 3m46s
[20:08:20.073] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:7:0)
[20:08:20.099] Build status: STARTED
[20:08:20.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:8:0) after 5m26s
[20:13:46.229] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:8:0)
[20:13:46.249] Build status: STARTED
[20:13:46.249] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:9:0) after 6m55s
[20:20:41.266] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:9:0)
[20:20:41.283] Build status: STARTED
[20:20:41.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:10:0) after 2m2s
[20:22:43.304] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:10:0)
[20:22:43.325] Build status: STARTED
[20:22:43.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:11:0) after 1m16s
[20:23:59.343] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:11:0)
[20:23:59.364] Build status: STARTED
[20:23:59.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:12:0) after 6m52s
[20:30:51.380] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:12:0)
[20:30:51.411] Build status: STARTED
[20:30:51.411] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:13:0) after 4m44s
[20:35:35.520] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:13:0)
[20:35:35.536] Build status: STARTED
[20:35:35.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:14:0) after 2m25s
[20:38:00.565] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:14:0)
[20:38:00.586] Build status: STARTED
[20:38:00.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:15:0) after 9m22s
[20:47:22.703] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:15:0)
[20:47:22.737] Build status: STARTED
[20:47:22.737] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:16:0) after 3m57s
[20:51:19.762] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:16:0)
[20:51:19.779] Build status: STARTED
[20:51:19.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:17:0) after 9m39s
[21:00:58.824] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:17:0)
[21:00:58.845] Build status: STARTED
[21:00:58.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:18:0) after 9m5s
[21:10:03.864] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:18:0)
[21:10:03.885] Build status: STARTED
[21:10:03.885] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:19:0) after 7m18s
[21:17:21.955] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:19:0)
[21:17:21.976] Build status: STARTED
[21:17:21.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:20:0) after 1m54s
[21:19:15.991] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:20:0)
[21:19:16.010] Build status: STARTED
[21:19:16.010] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:21:0) after 6m1s
[21:25:17.143] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:21:0)
[21:25:17.163] Build status: STARTED
[21:25:17.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:22:0) after 7m18s
[21:32:35.233] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:22:0)
[21:32:35.255] Build status: STARTED
[21:32:35.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:23:0) after 5m41s
[21:38:16.275] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:23:0)
[21:38:16.293] Build status: STARTED
[21:38:16.293] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899775455192574320:24:0) after 8m23s
[21:39:05.325] Received PubSub notification, asking Buildbucket for the build status
[21:39:05.345] Build:
{
"id": "8734345392958229329",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-11T19:49:10.322107694Z",
"startTime": "2024-10-11T19:49:16.720093199Z",
"endTime": "2024-10-11T21:39:04.688136462Z",
"updateTime": "2024-10-11T21:39:04.688136462Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "652bd76a3c122bfdacc3d67d1b37bbf495c5fbfa",
"ref": "refs/heads/main"
}
}
}
[21:39:05.345] Invocation finished in 1h49m56.537131747s with status SUCCEEDED