[20:41:11.176] New invocation is queued and will start shortly
[20:41:12.975] Starting the invocation (attempt 1)
[20:41:13.001] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/7a032fe891795293433cb9ec3238c251ba6a6d07"
[20:41:13.001] Popped gitiles commit info from properties and tags
[20:41:13.002] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:41:13.002] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:41:13.002] Buildbucket request:
{
"requestId": "8901765314427070912",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8901765314427070912",
"job": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "c414429e614ed8b028199821bf9d21afd7d3e59f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@c414429e614ed8b028199821bf9d21afd7d3e59f",
"title": "c414429e614ed8b028199821bf9d21afd7d3e59f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/c414429e614ed8b028199821bf9d21afd7d3e59f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "9f2063682725c75f4552a04926011fc2b4d25a70"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@9f2063682725c75f4552a04926011fc2b4d25a70",
"title": "9f2063682725c75f4552a04926011fc2b4d25a70",
"url": "https://fuchsia.googlesource.com/fuchsia/+/9f2063682725c75f4552a04926011fc2b4d25a70"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "ff9cfc222a36c511d0ee01de31821032d2bd6d24"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@ff9cfc222a36c511d0ee01de31821032d2bd6d24",
"title": "ff9cfc222a36c511d0ee01de31821032d2bd6d24",
"url": "https://fuchsia.googlesource.com/fuchsia/+/ff9cfc222a36c511d0ee01de31821032d2bd6d24"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "cfbcdfcb15170cedeba3cd2b7e8c7b68415e199b"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@cfbcdfcb15170cedeba3cd2b7e8c7b68415e199b",
"title": "cfbcdfcb15170cedeba3cd2b7e8c7b68415e199b",
"url": "https://fuchsia.googlesource.com/fuchsia/+/cfbcdfcb15170cedeba3cd2b7e8c7b68415e199b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "49d247db2bbc664646c30f50e730b4b343a40a80"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@49d247db2bbc664646c30f50e730b4b343a40a80",
"title": "49d247db2bbc664646c30f50e730b4b343a40a80",
"url": "https://fuchsia.googlesource.com/fuchsia/+/49d247db2bbc664646c30f50e730b4b343a40a80"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "807e29231653a53d151cb08c2734f907e65cf811"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@807e29231653a53d151cb08c2734f907e65cf811",
"title": "807e29231653a53d151cb08c2734f907e65cf811",
"url": "https://fuchsia.googlesource.com/fuchsia/+/807e29231653a53d151cb08c2734f907e65cf811"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8eb5eb1702282f1fef1b5d172f57594197541f04"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8eb5eb1702282f1fef1b5d172f57594197541f04",
"title": "8eb5eb1702282f1fef1b5d172f57594197541f04",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8eb5eb1702282f1fef1b5d172f57594197541f04"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "4a0b7cb8bd7112bbece261f2d40396df90eb5a3b"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@4a0b7cb8bd7112bbece261f2d40396df90eb5a3b",
"title": "4a0b7cb8bd7112bbece261f2d40396df90eb5a3b",
"url": "https://fuchsia.googlesource.com/fuchsia/+/4a0b7cb8bd7112bbece261f2d40396df90eb5a3b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "559957d269bac2b11e0834a59396ce2499208d42"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@559957d269bac2b11e0834a59396ce2499208d42",
"title": "559957d269bac2b11e0834a59396ce2499208d42",
"url": "https://fuchsia.googlesource.com/fuchsia/+/559957d269bac2b11e0834a59396ce2499208d42"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6c90cd580ee2fe6c822eafab4d9049120a9ad6f4"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6c90cd580ee2fe6c822eafab4d9049120a9ad6f4",
"title": "6c90cd580ee2fe6c822eafab4d9049120a9ad6f4",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6c90cd580ee2fe6c822eafab4d9049120a9ad6f4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "10be5639cf1506cafe6d0613cc1c00d25cb7368a"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@10be5639cf1506cafe6d0613cc1c00d25cb7368a",
"title": "10be5639cf1506cafe6d0613cc1c00d25cb7368a",
"url": "https://fuchsia.googlesource.com/fuchsia/+/10be5639cf1506cafe6d0613cc1c00d25cb7368a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "290e0e76486ebcd8a30d79071d8e16a09708c3a2"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@290e0e76486ebcd8a30d79071d8e16a09708c3a2",
"title": "290e0e76486ebcd8a30d79071d8e16a09708c3a2",
"url": "https://fuchsia.googlesource.com/fuchsia/+/290e0e76486ebcd8a30d79071d8e16a09708c3a2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "f445bd2253b999032457fa84279fbc1821ab6225"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@f445bd2253b999032457fa84279fbc1821ab6225",
"title": "f445bd2253b999032457fa84279fbc1821ab6225",
"url": "https://fuchsia.googlesource.com/fuchsia/+/f445bd2253b999032457fa84279fbc1821ab6225"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "0f193f288c9dc038c016301b9e8a8afae63f5d73"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@0f193f288c9dc038c016301b9e8a8afae63f5d73",
"title": "0f193f288c9dc038c016301b9e8a8afae63f5d73",
"url": "https://fuchsia.googlesource.com/fuchsia/+/0f193f288c9dc038c016301b9e8a8afae63f5d73"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a705ac04fe1d6ae2637653d0d2cbced9c23aba57"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a705ac04fe1d6ae2637653d0d2cbced9c23aba57",
"title": "a705ac04fe1d6ae2637653d0d2cbced9c23aba57",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a705ac04fe1d6ae2637653d0d2cbced9c23aba57"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a4203c5d193ced5203f2f23f87204d235a6a7c42"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a4203c5d193ced5203f2f23f87204d235a6a7c42",
"title": "a4203c5d193ced5203f2f23f87204d235a6a7c42",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a4203c5d193ced5203f2f23f87204d235a6a7c42"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "1c8d1212cd71b16c5c276aae18dc10a574c0909d"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@1c8d1212cd71b16c5c276aae18dc10a574c0909d",
"title": "1c8d1212cd71b16c5c276aae18dc10a574c0909d",
"url": "https://fuchsia.googlesource.com/fuchsia/+/1c8d1212cd71b16c5c276aae18dc10a574c0909d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "2eb417e440a7bda28d78dc5c4d1cfa91c6d35c9f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@2eb417e440a7bda28d78dc5c4d1cfa91c6d35c9f",
"title": "2eb417e440a7bda28d78dc5c4d1cfa91c6d35c9f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/2eb417e440a7bda28d78dc5c4d1cfa91c6d35c9f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "dbf896555c0aefda78e4d55a22ed54c7200ba3ea"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@dbf896555c0aefda78e4d55a22ed54c7200ba3ea",
"title": "dbf896555c0aefda78e4d55a22ed54c7200ba3ea",
"url": "https://fuchsia.googlesource.com/fuchsia/+/dbf896555c0aefda78e4d55a22ed54c7200ba3ea"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "50dd19ae348d2b51c8d34fe30687db9e1de5e2dd"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@50dd19ae348d2b51c8d34fe30687db9e1de5e2dd",
"title": "50dd19ae348d2b51c8d34fe30687db9e1de5e2dd",
"url": "https://fuchsia.googlesource.com/fuchsia/+/50dd19ae348d2b51c8d34fe30687db9e1de5e2dd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "7a032fe891795293433cb9ec3238c251ba6a6d07"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@7a032fe891795293433cb9ec3238c251ba6a6d07",
"title": "7a032fe891795293433cb9ec3238c251ba6a6d07",
"url": "https://fuchsia.googlesource.com/fuchsia/+/7a032fe891795293433cb9ec3238c251ba6a6d07"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "7a032fe891795293433cb9ec3238c251ba6a6d07",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8901765314427070912"
},
{
"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"
}
}
[20:41:13.931] Scheduled build:
{
"id": "8736335251701099809",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-19T20:41:13.146743515Z",
"updateTime": "2024-09-19T20:41:13.146743515Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "7a032fe891795293433cb9ec3238c251ba6a6d07",
"ref": "refs/heads/main"
}
}
}
[20:41:13.931] Task URL: https://cr-buildbucket.appspot.com/build/8736335251701099809
[20:41:13.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:2:0) after 8m28s
[20:41:14.785] Received PubSub notification, asking Buildbucket for the build status
[20:41:14.806] Build status: SCHEDULED
[20:41:19.862] Received PubSub notification, asking Buildbucket for the build status
[20:41:19.885] Build status: STARTED
[20:49:41.977] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:2:0)
[20:49:42.130] Build status: STARTED
[20:49:42.130] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:5:0) after 7m56s
[20:57:38.147] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:5:0)
[20:57:38.164] Build status: STARTED
[20:57:38.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:6:0) after 6m38s
[21:04:16.168] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:6:0)
[21:04:16.186] Build status: STARTED
[21:04:16.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:7:0) after 3m25s
[21:07:41.205] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:7:0)
[21:07:41.240] Build status: STARTED
[21:07:41.240] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:8:0) after 7m37s
[21:15:18.423] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:8:0)
[21:15:18.444] Build status: STARTED
[21:15:18.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:9:0) after 1m32s
[21:16:50.467] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:9:0)
[21:16:50.551] Build status: STARTED
[21:16:50.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:10:0) after 6m33s
[21:23:23.619] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:10:0)
[21:23:23.634] Build status: STARTED
[21:23:23.634] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:11:0) after 1m37s
[21:25:00.705] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:11:0)
[21:25:00.727] Build status: STARTED
[21:25:00.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:12:0) after 4m14s
[21:29:14.792] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:12:0)
[21:29:14.840] Build status: STARTED
[21:29:14.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:13:0) after 3m13s
[21:32:27.860] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:13:0)
[21:32:28.013] Build status: STARTED
[21:32:28.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:14:0) after 3m37s
[21:36:05.032] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:14:0)
[21:36:05.053] Build status: STARTED
[21:36:05.053] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:15:0) after 8m44s
[21:44:49.068] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:15:0)
[21:44:49.087] Build status: STARTED
[21:44:49.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:16:0) after 6m24s
[21:51:13.217] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:16:0)
[21:51:13.286] Build status: STARTED
[21:51:13.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:17:0) after 6m55s
[21:58:08.305] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:17:0)
[21:58:08.339] Build status: STARTED
[21:58:08.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:18:0) after 6m37s
[22:04:45.336] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:18:0)
[22:04:45.375] Build status: STARTED
[22:04:45.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:19:0) after 9m25s
[22:14:10.377] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:19:0)
[22:14:10.399] Build status: STARTED
[22:14:10.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:20:0) after 6m0s
[22:20:10.467] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:20:0)
[22:20:10.495] Build status: STARTED
[22:20:10.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:21:0) after 4m58s
[22:25:08.709] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:21:0)
[22:25:08.760] Build status: STARTED
[22:25:08.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:22:0) after 4m35s
[22:29:43.776] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:22:0)
[22:29:43.794] Build status: STARTED
[22:29:43.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:23:0) after 5m4s
[22:34:47.833] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:23:0)
[22:34:47.860] Build status: STARTED
[22:34:47.860] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901765314427070912:24:0) after 8m47s
[22:38:43.982] Received PubSub notification, asking Buildbucket for the build status
[22:38:44.038] Build:
{
"id": "8736335251701099809",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-19T20:41:13.146743515Z",
"startTime": "2024-09-19T20:41:19.666472284Z",
"endTime": "2024-09-19T22:38:43.268885889Z",
"updateTime": "2024-09-19T22:38:43.268885889Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "7a032fe891795293433cb9ec3238c251ba6a6d07",
"ref": "refs/heads/main"
}
}
}
[22:38:44.038] Invocation finished in 1h57m32.87564738s with status SUCCEEDED