[22:08:24.909] New invocation is queued and will start shortly
[22:08:26.216] Starting the invocation (attempt 1)
[22:08:26.246] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/e0c62ba2c2ab5dd3efcd88a50bc84e41c82568e8"
[22:08:26.246] Popped gitiles commit info from properties and tags
[22:08:26.246] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:08:26.246] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:08:26.246] Buildbucket request:
{
"requestId": "8902031617351391232",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8902031617351391232",
"job": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "582ee2b43f9d1fe0c12690147420f0ab6effdeec"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@582ee2b43f9d1fe0c12690147420f0ab6effdeec",
"title": "582ee2b43f9d1fe0c12690147420f0ab6effdeec",
"url": "https://fuchsia.googlesource.com/fuchsia/+/582ee2b43f9d1fe0c12690147420f0ab6effdeec"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a70641a9cfc6d68ee9081d9ff8abf92dbb0bd34a"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a70641a9cfc6d68ee9081d9ff8abf92dbb0bd34a",
"title": "a70641a9cfc6d68ee9081d9ff8abf92dbb0bd34a",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a70641a9cfc6d68ee9081d9ff8abf92dbb0bd34a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "4fd7ea5e41f0f43dac56de488a844dcd5586b389"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@4fd7ea5e41f0f43dac56de488a844dcd5586b389",
"title": "4fd7ea5e41f0f43dac56de488a844dcd5586b389",
"url": "https://fuchsia.googlesource.com/fuchsia/+/4fd7ea5e41f0f43dac56de488a844dcd5586b389"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6aba85559de80dc57c815a455481ce2b91abf3be"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6aba85559de80dc57c815a455481ce2b91abf3be",
"title": "6aba85559de80dc57c815a455481ce2b91abf3be",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6aba85559de80dc57c815a455481ce2b91abf3be"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "e09c7ba5ea132fac92516de7e4a94d84d8b87ce5"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@e09c7ba5ea132fac92516de7e4a94d84d8b87ce5",
"title": "e09c7ba5ea132fac92516de7e4a94d84d8b87ce5",
"url": "https://fuchsia.googlesource.com/fuchsia/+/e09c7ba5ea132fac92516de7e4a94d84d8b87ce5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "0bcd9ee62a2ecc7040d175524121b30d7aedba45"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@0bcd9ee62a2ecc7040d175524121b30d7aedba45",
"title": "0bcd9ee62a2ecc7040d175524121b30d7aedba45",
"url": "https://fuchsia.googlesource.com/fuchsia/+/0bcd9ee62a2ecc7040d175524121b30d7aedba45"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "9b4925f4ece41aa9227f18434e16293e58589f49"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@9b4925f4ece41aa9227f18434e16293e58589f49",
"title": "9b4925f4ece41aa9227f18434e16293e58589f49",
"url": "https://fuchsia.googlesource.com/fuchsia/+/9b4925f4ece41aa9227f18434e16293e58589f49"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "30884822d12b658bae6bdac08f0c680b632aa2b2"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@30884822d12b658bae6bdac08f0c680b632aa2b2",
"title": "30884822d12b658bae6bdac08f0c680b632aa2b2",
"url": "https://fuchsia.googlesource.com/fuchsia/+/30884822d12b658bae6bdac08f0c680b632aa2b2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "94e11e12bb9ac0b6a54bfd6e08e1dc981ecce67f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@94e11e12bb9ac0b6a54bfd6e08e1dc981ecce67f",
"title": "94e11e12bb9ac0b6a54bfd6e08e1dc981ecce67f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/94e11e12bb9ac0b6a54bfd6e08e1dc981ecce67f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "e083e777bbae5beba11063229c9bb3fdd70ba6e7"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@e083e777bbae5beba11063229c9bb3fdd70ba6e7",
"title": "e083e777bbae5beba11063229c9bb3fdd70ba6e7",
"url": "https://fuchsia.googlesource.com/fuchsia/+/e083e777bbae5beba11063229c9bb3fdd70ba6e7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "ea52081fe8bc2151900cce70fdcf31eb4392c4a3"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@ea52081fe8bc2151900cce70fdcf31eb4392c4a3",
"title": "ea52081fe8bc2151900cce70fdcf31eb4392c4a3",
"url": "https://fuchsia.googlesource.com/fuchsia/+/ea52081fe8bc2151900cce70fdcf31eb4392c4a3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "261947bb8e001708e660b0b3f84c9be5fc77f1ab"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@261947bb8e001708e660b0b3f84c9be5fc77f1ab",
"title": "261947bb8e001708e660b0b3f84c9be5fc77f1ab",
"url": "https://fuchsia.googlesource.com/fuchsia/+/261947bb8e001708e660b0b3f84c9be5fc77f1ab"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "e0c62ba2c2ab5dd3efcd88a50bc84e41c82568e8"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@e0c62ba2c2ab5dd3efcd88a50bc84e41c82568e8",
"title": "e0c62ba2c2ab5dd3efcd88a50bc84e41c82568e8",
"url": "https://fuchsia.googlesource.com/fuchsia/+/e0c62ba2c2ab5dd3efcd88a50bc84e41c82568e8"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "e0c62ba2c2ab5dd3efcd88a50bc84e41c82568e8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8902031617351391232"
},
{
"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"
}
}
[22:08:26.705] Scheduled build:
{
"id": "8736601555228704817",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-16T22:08:26.311996191Z",
"updateTime": "2024-09-16T22:08:26.311996191Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "e0c62ba2c2ab5dd3efcd88a50bc84e41c82568e8",
"ref": "refs/heads/main"
}
}
}
[22:08:26.705] Task URL: https://cr-buildbucket.appspot.com/build/8736601555228704817
[22:08:26.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:2:0) after 5m35s
[22:08:47.269] Received PubSub notification, asking Buildbucket for the build status
[22:08:47.354] Build status: STARTED
[22:08:53.916] Received PubSub notification, asking Buildbucket for the build status
[22:08:53.982] Build status: STARTED
[22:14:01.758] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:2:0)
[22:14:01.776] Build status: STARTED
[22:14:01.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:5:0) after 4m30s
[22:18:31.799] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:5:0)
[22:18:31.883] Build status: STARTED
[22:18:31.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:6:0) after 1m59s
[22:20:30.932] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:6:0)
[22:20:30.960] Build status: STARTED
[22:20:30.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:7:0) after 7m19s
[22:27:49.980] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:7:0)
[22:27:50.005] Build status: STARTED
[22:27:50.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:8:0) after 2m25s
[22:30:15.053] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:8:0)
[22:30:15.077] Build status: STARTED
[22:30:15.077] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:9:0) after 3m19s
[22:33:34.095] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:9:0)
[22:33:34.128] Build status: STARTED
[22:33:34.128] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:10:0) after 2m5s
[22:35:39.195] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:10:0)
[22:35:39.219] Build status: STARTED
[22:35:39.219] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:11:0) after 4m57s
[22:40:36.260] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:11:0)
[22:40:36.282] Build status: STARTED
[22:40:36.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:12:0) after 5m12s
[22:45:48.327] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:12:0)
[22:45:48.345] Build status: STARTED
[22:45:48.345] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:13:0) after 8m18s
[22:54:06.368] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:13:0)
[22:54:06.391] Build status: STARTED
[22:54:06.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:14:0) after 7m18s
[23:01:24.422] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:14:0)
[23:01:24.714] Build status: STARTED
[23:01:24.714] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:15:0) after 3m10s
[23:04:34.742] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:15:0)
[23:04:34.763] Build status: STARTED
[23:04:34.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:16:0) after 3m10s
[23:07:44.828] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:16:0)
[23:07:44.850] Build status: STARTED
[23:07:44.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:17:0) after 2m11s
[23:09:55.869] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:17:0)
[23:09:55.889] Build status: STARTED
[23:09:55.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:18:0) after 4m45s
[23:14:40.904] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:18:0)
[23:14:40.949] Build status: STARTED
[23:14:40.949] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:19:0) after 7m56s
[23:22:36.970] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:19:0)
[23:22:36.987] Build status: STARTED
[23:22:36.987] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:20:0) after 3m24s
[23:26:01.013] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:20:0)
[23:26:01.036] Build status: STARTED
[23:26:01.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:21:0) after 5m15s
[23:31:16.104] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:21:0)
[23:31:16.142] Build status: STARTED
[23:31:16.142] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:22:0) after 1m38s
[23:32:54.198] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:22:0)
[23:32:54.215] Build status: STARTED
[23:32:54.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902031617351391232:23:0) after 6m58s
[23:35:21.896] Received PubSub notification, asking Buildbucket for the build status
[23:35:21.943] Build:
{
"id": "8736601555228704817",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-16T22:08:26.311996191Z",
"startTime": "2024-09-16T22:08:33.417870827Z",
"endTime": "2024-09-16T23:35:21.430711840Z",
"updateTime": "2024-09-16T23:35:21.430711840Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "e0c62ba2c2ab5dd3efcd88a50bc84e41c82568e8",
"ref": "refs/heads/main"
}
}
}
[23:35:21.943] Invocation finished in 1h26m57.039933754s with status SUCCEEDED