[23:17:48.855] New invocation is queued and will start shortly
[23:17:50.082] Starting the invocation (attempt 1)
[23:17:50.114] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/40f6f4b42c060687a1ae932be434bbf87fe68b3c"
[23:17:50.114] Popped gitiles commit info from properties and tags
[23:17:50.114] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:17:50.114] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:17:50.115] Buildbucket request:
{
"requestId": "8902661429906065856",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8902661429906065856",
"job": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a697ad6329fab8b95378f927f67fe701a2b30286"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a697ad6329fab8b95378f927f67fe701a2b30286",
"title": "a697ad6329fab8b95378f927f67fe701a2b30286",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a697ad6329fab8b95378f927f67fe701a2b30286"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "91ee8c9ef952c8024472c912f39e6e6eb815113b"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@91ee8c9ef952c8024472c912f39e6e6eb815113b",
"title": "91ee8c9ef952c8024472c912f39e6e6eb815113b",
"url": "https://fuchsia.googlesource.com/fuchsia/+/91ee8c9ef952c8024472c912f39e6e6eb815113b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "219f1dc9e9f0cd36d1f68febcd3e4e024b5f4cc7"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@219f1dc9e9f0cd36d1f68febcd3e4e024b5f4cc7",
"title": "219f1dc9e9f0cd36d1f68febcd3e4e024b5f4cc7",
"url": "https://fuchsia.googlesource.com/fuchsia/+/219f1dc9e9f0cd36d1f68febcd3e4e024b5f4cc7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "34ea164050679acda0a3bb9d05441ef3e8cc9f63"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@34ea164050679acda0a3bb9d05441ef3e8cc9f63",
"title": "34ea164050679acda0a3bb9d05441ef3e8cc9f63",
"url": "https://fuchsia.googlesource.com/fuchsia/+/34ea164050679acda0a3bb9d05441ef3e8cc9f63"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "f25f21761ec016403906758f80a7263c58b3fa92"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@f25f21761ec016403906758f80a7263c58b3fa92",
"title": "f25f21761ec016403906758f80a7263c58b3fa92",
"url": "https://fuchsia.googlesource.com/fuchsia/+/f25f21761ec016403906758f80a7263c58b3fa92"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "ead5ae971203c2ad85c6be9e82a1ad388c13ff75"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@ead5ae971203c2ad85c6be9e82a1ad388c13ff75",
"title": "ead5ae971203c2ad85c6be9e82a1ad388c13ff75",
"url": "https://fuchsia.googlesource.com/fuchsia/+/ead5ae971203c2ad85c6be9e82a1ad388c13ff75"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "f1101f723c4bc91872c863169ba4cda2b36c02e7"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@f1101f723c4bc91872c863169ba4cda2b36c02e7",
"title": "f1101f723c4bc91872c863169ba4cda2b36c02e7",
"url": "https://fuchsia.googlesource.com/fuchsia/+/f1101f723c4bc91872c863169ba4cda2b36c02e7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "3b2163355f7589f0d64905a4f88a832b2768881f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@3b2163355f7589f0d64905a4f88a832b2768881f",
"title": "3b2163355f7589f0d64905a4f88a832b2768881f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/3b2163355f7589f0d64905a4f88a832b2768881f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "d727afe02391fd76e92d10dd5f523bc446ad0af9"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@d727afe02391fd76e92d10dd5f523bc446ad0af9",
"title": "d727afe02391fd76e92d10dd5f523bc446ad0af9",
"url": "https://fuchsia.googlesource.com/fuchsia/+/d727afe02391fd76e92d10dd5f523bc446ad0af9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a9114cbbead976e14d4bad1b99f5656c618f9213"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a9114cbbead976e14d4bad1b99f5656c618f9213",
"title": "a9114cbbead976e14d4bad1b99f5656c618f9213",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a9114cbbead976e14d4bad1b99f5656c618f9213"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "908854ed6d8682ea25caa1f39012e4aa7e8f477f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@908854ed6d8682ea25caa1f39012e4aa7e8f477f",
"title": "908854ed6d8682ea25caa1f39012e4aa7e8f477f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/908854ed6d8682ea25caa1f39012e4aa7e8f477f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a6f304d240bbbc3597b28aa008f660eb64e1f4e4"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a6f304d240bbbc3597b28aa008f660eb64e1f4e4",
"title": "a6f304d240bbbc3597b28aa008f660eb64e1f4e4",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a6f304d240bbbc3597b28aa008f660eb64e1f4e4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "14f96c47133f3e382e615f7fa79454d0137dea78"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@14f96c47133f3e382e615f7fa79454d0137dea78",
"title": "14f96c47133f3e382e615f7fa79454d0137dea78",
"url": "https://fuchsia.googlesource.com/fuchsia/+/14f96c47133f3e382e615f7fa79454d0137dea78"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6f074f8fdc99af835a95a3cfdd88093495c7ec6e"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6f074f8fdc99af835a95a3cfdd88093495c7ec6e",
"title": "6f074f8fdc99af835a95a3cfdd88093495c7ec6e",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6f074f8fdc99af835a95a3cfdd88093495c7ec6e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "582b8722591e68f4049c40d7d5759db8212db155"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@582b8722591e68f4049c40d7d5759db8212db155",
"title": "582b8722591e68f4049c40d7d5759db8212db155",
"url": "https://fuchsia.googlesource.com/fuchsia/+/582b8722591e68f4049c40d7d5759db8212db155"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "dce09d6533edabccb677a65a67aed818b1c23bb0"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@dce09d6533edabccb677a65a67aed818b1c23bb0",
"title": "dce09d6533edabccb677a65a67aed818b1c23bb0",
"url": "https://fuchsia.googlesource.com/fuchsia/+/dce09d6533edabccb677a65a67aed818b1c23bb0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "cdcdf6d1dc5051009c2e7562804a8def7f84679f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@cdcdf6d1dc5051009c2e7562804a8def7f84679f",
"title": "cdcdf6d1dc5051009c2e7562804a8def7f84679f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/cdcdf6d1dc5051009c2e7562804a8def7f84679f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "40f6f4b42c060687a1ae932be434bbf87fe68b3c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@40f6f4b42c060687a1ae932be434bbf87fe68b3c",
"title": "40f6f4b42c060687a1ae932be434bbf87fe68b3c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/40f6f4b42c060687a1ae932be434bbf87fe68b3c"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "40f6f4b42c060687a1ae932be434bbf87fe68b3c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8902661429906065856"
},
{
"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"
}
}
[23:17:50.574] Scheduled build:
{
"id": "8737231367798042593",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-09T23:17:50.239385561Z",
"updateTime": "2024-09-09T23:17:50.239385561Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "40f6f4b42c060687a1ae932be434bbf87fe68b3c",
"ref": "refs/heads/main"
}
}
}
[23:17:50.575] Task URL: https://cr-buildbucket.appspot.com/build/8737231367798042593
[23:17:50.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:2:0) after 1m8s
[23:17:51.513] Received PubSub notification, asking Buildbucket for the build status
[23:17:51.540] Build status: SCHEDULED
[23:17:55.828] Received PubSub notification, asking Buildbucket for the build status
[23:17:55.846] Build status: STARTED
[23:18:58.642] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:2:0)
[23:18:58.685] Build status: STARTED
[23:18:58.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:5:0) after 4m10s
[23:23:08.721] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:5:0)
[23:23:08.744] Build status: STARTED
[23:23:08.744] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:6:0) after 2m1s
[23:25:09.760] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:6:0)
[23:25:09.775] Build status: STARTED
[23:25:09.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:7:0) after 4m44s
[23:29:53.913] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:7:0)
[23:29:53.931] Build status: STARTED
[23:29:53.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:8:0) after 4m46s
[23:34:39.954] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:8:0)
[23:34:39.973] Build status: STARTED
[23:34:39.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:9:0) after 3m11s
[23:37:51.040] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:9:0)
[23:37:51.061] Build status: STARTED
[23:37:51.061] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:10:0) after 4m25s
[23:42:16.114] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:10:0)
[23:42:16.138] Build status: STARTED
[23:42:16.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:11:0) after 6m33s
[23:48:49.128] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:11:0)
[23:48:49.145] Build status: STARTED
[23:48:49.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:12:0) after 3m41s
[23:52:30.359] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:12:0)
[23:52:30.419] Build status: STARTED
[23:52:30.419] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:13:0) after 9m27s
[00:01:57.478] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:13:0)
[00:01:57.497] Build status: STARTED
[00:01:57.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:14:0) after 1m24s
[00:03:21.580] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:14:0)
[00:03:21.596] Build status: STARTED
[00:03:21.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:15:0) after 4m5s
[00:07:26.616] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:15:0)
[00:07:26.633] Build status: STARTED
[00:07:26.633] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:16:0) after 3m48s
[00:11:14.613] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:16:0)
[00:11:14.635] Build status: STARTED
[00:11:14.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:17:0) after 3m53s
[00:15:07.652] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:17:0)
[00:15:07.674] Build status: STARTED
[00:15:07.674] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:18:0) after 1m38s
[00:16:45.713] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:18:0)
[00:16:45.755] Build status: STARTED
[00:16:45.755] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:19:0) after 4m8s
[00:20:53.751] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:19:0)
[00:20:53.827] Build status: STARTED
[00:20:53.827] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:20:0) after 1m18s
[00:22:11.931] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:20:0)
[00:22:11.964] Build status: STARTED
[00:22:11.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:21:0) after 9m49s
[00:32:01.150] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:21:0)
[00:32:01.173] Build status: STARTED
[00:32:01.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:22:0) after 2m36s
[00:34:37.191] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:22:0)
[00:34:37.210] Build status: STARTED
[00:34:37.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:23:0) after 8m41s
[00:43:18.330] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:23:0)
[00:43:18.368] Build status: STARTED
[00:43:18.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:24:0) after 4m53s
[00:48:11.446] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:24:0)
[00:48:11.466] Build status: STARTED
[00:48:11.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:25:0) after 9m19s
[00:57:30.484] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:25:0)
[00:57:30.501] Build status: STARTED
[00:57:30.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:26:0) after 5m29s
[01:02:59.523] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:26:0)
[01:02:59.542] Build status: STARTED
[01:02:59.542] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:27:0) after 2m45s
[01:05:44.557] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:27:0)
[01:05:44.576] Build status: STARTED
[01:05:44.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902661429906065856:28:0) after 6m29s
[01:11:00.588] Received PubSub notification, asking Buildbucket for the build status
[01:11:00.607] Build:
{
"id": "8737231367798042593",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-09T23:17:50.239385561Z",
"startTime": "2024-09-09T23:17:55.594341463Z",
"endTime": "2024-09-10T01:10:58.403139006Z",
"updateTime": "2024-09-10T01:10:58.403139006Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "40f6f4b42c060687a1ae932be434bbf87fe68b3c",
"ref": "refs/heads/main"
}
}
}
[01:11:00.607] Invocation finished in 1h53m11.760523005s with status SUCCEEDED