[01:08:00.772] New invocation is queued and will start shortly
[01:08:01.881] Starting the invocation (attempt 1)
[01:08:01.912] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/aa6559e9bde2c2d0fd32c0e7da2b5838282176a6"
[01:08:01.913] Popped gitiles commit info from properties and tags
[01:08:01.913] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:08:01.913] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:08:01.913] Buildbucket request:
{
"requestId": "8901839124111353600",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8901839124111353600",
"job": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "1b31e96cd6d878ca74683363eea5e708520ad649"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@1b31e96cd6d878ca74683363eea5e708520ad649",
"title": "1b31e96cd6d878ca74683363eea5e708520ad649",
"url": "https://fuchsia.googlesource.com/fuchsia/+/1b31e96cd6d878ca74683363eea5e708520ad649"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8555a1198a11f8ea7c183afbc135f63f7ce88371"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8555a1198a11f8ea7c183afbc135f63f7ce88371",
"title": "8555a1198a11f8ea7c183afbc135f63f7ce88371",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8555a1198a11f8ea7c183afbc135f63f7ce88371"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "2d2a6a15bae59d84ea197ec1d9d0345d3c441dcd"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@2d2a6a15bae59d84ea197ec1d9d0345d3c441dcd",
"title": "2d2a6a15bae59d84ea197ec1d9d0345d3c441dcd",
"url": "https://fuchsia.googlesource.com/fuchsia/+/2d2a6a15bae59d84ea197ec1d9d0345d3c441dcd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "1c913af736c9ddca70162b33febb45c6de7a781a"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@1c913af736c9ddca70162b33febb45c6de7a781a",
"title": "1c913af736c9ddca70162b33febb45c6de7a781a",
"url": "https://fuchsia.googlesource.com/fuchsia/+/1c913af736c9ddca70162b33febb45c6de7a781a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "aa0af2bfff991691788c03d076e287d7ee10b70a"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@aa0af2bfff991691788c03d076e287d7ee10b70a",
"title": "aa0af2bfff991691788c03d076e287d7ee10b70a",
"url": "https://fuchsia.googlesource.com/fuchsia/+/aa0af2bfff991691788c03d076e287d7ee10b70a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "10925e970cbd6c5c3c7ea9dc9f8e4fd4167263cd"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@10925e970cbd6c5c3c7ea9dc9f8e4fd4167263cd",
"title": "10925e970cbd6c5c3c7ea9dc9f8e4fd4167263cd",
"url": "https://fuchsia.googlesource.com/fuchsia/+/10925e970cbd6c5c3c7ea9dc9f8e4fd4167263cd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "d27eb3c0f515ec18c9a98e780794a4a83a813232"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@d27eb3c0f515ec18c9a98e780794a4a83a813232",
"title": "d27eb3c0f515ec18c9a98e780794a4a83a813232",
"url": "https://fuchsia.googlesource.com/fuchsia/+/d27eb3c0f515ec18c9a98e780794a4a83a813232"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "40ac49b34bd29a3af52747caf9aa0f09147fb806"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@40ac49b34bd29a3af52747caf9aa0f09147fb806",
"title": "40ac49b34bd29a3af52747caf9aa0f09147fb806",
"url": "https://fuchsia.googlesource.com/fuchsia/+/40ac49b34bd29a3af52747caf9aa0f09147fb806"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8593c80220781baa1a6e9f6608585dbf72565337"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8593c80220781baa1a6e9f6608585dbf72565337",
"title": "8593c80220781baa1a6e9f6608585dbf72565337",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8593c80220781baa1a6e9f6608585dbf72565337"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "32852454dab0ffe6b4c92eae33499c6ce955527d"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@32852454dab0ffe6b4c92eae33499c6ce955527d",
"title": "32852454dab0ffe6b4c92eae33499c6ce955527d",
"url": "https://fuchsia.googlesource.com/fuchsia/+/32852454dab0ffe6b4c92eae33499c6ce955527d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "451a8c75a937bcae5e4c6f77f2ca86fdd9eb5ce3"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@451a8c75a937bcae5e4c6f77f2ca86fdd9eb5ce3",
"title": "451a8c75a937bcae5e4c6f77f2ca86fdd9eb5ce3",
"url": "https://fuchsia.googlesource.com/fuchsia/+/451a8c75a937bcae5e4c6f77f2ca86fdd9eb5ce3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "aa6559e9bde2c2d0fd32c0e7da2b5838282176a6"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@aa6559e9bde2c2d0fd32c0e7da2b5838282176a6",
"title": "aa6559e9bde2c2d0fd32c0e7da2b5838282176a6",
"url": "https://fuchsia.googlesource.com/fuchsia/+/aa6559e9bde2c2d0fd32c0e7da2b5838282176a6"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "aa6559e9bde2c2d0fd32c0e7da2b5838282176a6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8901839124111353600"
},
{
"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"
}
}
[01:08:02.428] Scheduled build:
{
"id": "8736409062150749329",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-19T01:08:02.016970288Z",
"updateTime": "2024-09-19T01:08:02.016970288Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "aa6559e9bde2c2d0fd32c0e7da2b5838282176a6",
"ref": "refs/heads/main"
}
}
}
[01:08:02.428] Task URL: https://cr-buildbucket.appspot.com/build/8736409062150749329
[01:08:02.428] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:2:0) after 9m59s
[01:08:21.720] Received PubSub notification, asking Buildbucket for the build status
[01:08:21.750] Build status: STARTED
[01:08:28.822] Received PubSub notification, asking Buildbucket for the build status
[01:08:28.848] Build status: STARTED
[01:18:01.494] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:2:0)
[01:18:01.516] Build status: STARTED
[01:18:01.516] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:5:0) after 4m40s
[01:22:41.552] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:5:0)
[01:22:41.573] Build status: STARTED
[01:22:41.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:6:0) after 1m40s
[01:24:21.636] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:6:0)
[01:24:21.667] Build status: STARTED
[01:24:21.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:7:0) after 2m59s
[01:27:20.728] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:7:0)
[01:27:20.754] Build status: STARTED
[01:27:20.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:8:0) after 1m40s
[01:29:00.771] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:8:0)
[01:29:00.792] Build status: STARTED
[01:29:00.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:9:0) after 8m56s
[01:37:56.814] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:9:0)
[01:37:56.841] Build status: STARTED
[01:37:56.841] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:10:0) after 3m44s
[01:41:40.867] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:10:0)
[01:41:40.896] Build status: STARTED
[01:41:40.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:11:0) after 7m26s
[01:49:06.915] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:11:0)
[01:49:06.939] Build status: STARTED
[01:49:06.939] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:12:0) after 7m29s
[01:56:36.084] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:12:0)
[01:56:36.102] Build status: STARTED
[01:56:36.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:13:0) after 1m47s
[01:58:23.134] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:13:0)
[01:58:23.152] Build status: STARTED
[01:58:23.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:14:0) after 7m51s
[02:06:14.200] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:14:0)
[02:06:14.219] Build status: STARTED
[02:06:14.219] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:15:0) after 7m2s
[02:13:16.614] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:15:0)
[02:13:16.631] Build status: STARTED
[02:13:16.631] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:16:0) after 2m32s
[02:15:49.013] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:16:0)
[02:15:49.199] Build status: STARTED
[02:15:49.200] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:17:0) after 3m57s
[02:19:46.310] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:17:0)
[02:19:46.352] Build status: STARTED
[02:19:46.352] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:18:0) after 8m11s
[02:27:58.295] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:18:0)
[02:27:58.349] Build status: STARTED
[02:27:58.349] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:19:0) after 6m29s
[02:34:27.418] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:19:0)
[02:34:27.438] Build status: STARTED
[02:34:27.438] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:20:0) after 4m38s
[02:39:05.484] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:20:0)
[02:39:05.504] Build status: STARTED
[02:39:05.504] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:21:0) after 7m10s
[02:46:15.525] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:21:0)
[02:46:15.544] Build status: STARTED
[02:46:15.544] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:22:0) after 1m3s
[02:47:18.647] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:22:0)
[02:47:18.666] Build status: STARTED
[02:47:18.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:23:0) after 1m47s
[02:49:05.871] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:23:0)
[02:49:05.896] Build status: STARTED
[02:49:05.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:24:0) after 7m11s
[02:56:17.063] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:24:0)
[02:56:17.080] Build status: STARTED
[02:56:17.080] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:25:0) after 8m9s
[03:04:26.225] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:25:0)
[03:04:26.247] Build status: STARTED
[03:04:26.247] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:26:0) after 1m32s
[03:05:58.389] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:26:0)
[03:05:58.404] Build status: STARTED
[03:05:58.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:27:0) after 5m11s
[03:11:09.422] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:27:0)
[03:11:09.441] Build status: STARTED
[03:11:09.441] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901839124111353600:28:0) after 5m40s
[03:15:26.533] Received PubSub notification, asking Buildbucket for the build status
[03:15:26.550] Build:
{
"id": "8736409062150749329",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-19T01:08:02.016970288Z",
"startTime": "2024-09-19T01:08:08.432480362Z",
"endTime": "2024-09-19T03:15:25.880221771Z",
"updateTime": "2024-09-19T03:15:25.880221771Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "aa6559e9bde2c2d0fd32c0e7da2b5838282176a6",
"ref": "refs/heads/main"
}
}
}
[03:15:26.550] Invocation finished in 2h7m25.787877205s with status SUCCEEDED