[19:06:09.016] New invocation is queued and will start shortly
[19:06:10.219] Starting the invocation (attempt 1)
[19:06:10.251] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/014f0e28c3f3f9378c7ed4fcd2971cbcc1e9dfa4"
[19:06:10.251] Popped gitiles commit info from properties and tags
[19:06:10.251] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:06:10.251] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:06:10.252] Buildbucket request:
{
"requestId": "8913458302243385168",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8913458302243385168",
"job": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "2976f5fff0f276dd6018f97f1da4797c1b63cc03"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@2976f5fff0f276dd6018f97f1da4797c1b63cc03",
"title": "2976f5fff0f276dd6018f97f1da4797c1b63cc03",
"url": "https://fuchsia.googlesource.com/fuchsia/+/2976f5fff0f276dd6018f97f1da4797c1b63cc03"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "d2d4196fdb80ced2bdf5d06147c87025a3fbe5b1"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@d2d4196fdb80ced2bdf5d06147c87025a3fbe5b1",
"title": "d2d4196fdb80ced2bdf5d06147c87025a3fbe5b1",
"url": "https://fuchsia.googlesource.com/fuchsia/+/d2d4196fdb80ced2bdf5d06147c87025a3fbe5b1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "0f71b2257d3cd8f68da210805c0dfeb20b6ef71c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@0f71b2257d3cd8f68da210805c0dfeb20b6ef71c",
"title": "0f71b2257d3cd8f68da210805c0dfeb20b6ef71c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/0f71b2257d3cd8f68da210805c0dfeb20b6ef71c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a162dfc954b943aeb7db59dbccc55d0da1f39253"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a162dfc954b943aeb7db59dbccc55d0da1f39253",
"title": "a162dfc954b943aeb7db59dbccc55d0da1f39253",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a162dfc954b943aeb7db59dbccc55d0da1f39253"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "1afd4be87d88c9850aaf3e51fcc3b614ac931d0c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@1afd4be87d88c9850aaf3e51fcc3b614ac931d0c",
"title": "1afd4be87d88c9850aaf3e51fcc3b614ac931d0c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/1afd4be87d88c9850aaf3e51fcc3b614ac931d0c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "1fb31f7f6f0f5f3ae9f30a8c5a6f83d2cb05d4d5"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@1fb31f7f6f0f5f3ae9f30a8c5a6f83d2cb05d4d5",
"title": "1fb31f7f6f0f5f3ae9f30a8c5a6f83d2cb05d4d5",
"url": "https://fuchsia.googlesource.com/fuchsia/+/1fb31f7f6f0f5f3ae9f30a8c5a6f83d2cb05d4d5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6753a0211ddd9ad2ba36404ca7a6a1dbe83707b2"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6753a0211ddd9ad2ba36404ca7a6a1dbe83707b2",
"title": "6753a0211ddd9ad2ba36404ca7a6a1dbe83707b2",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6753a0211ddd9ad2ba36404ca7a6a1dbe83707b2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "7d738edab6db70110b4c467cfcccefe9ce51dd1a"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@7d738edab6db70110b4c467cfcccefe9ce51dd1a",
"title": "7d738edab6db70110b4c467cfcccefe9ce51dd1a",
"url": "https://fuchsia.googlesource.com/fuchsia/+/7d738edab6db70110b4c467cfcccefe9ce51dd1a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "eda9cd31750e687d990839e38007251aa3fe08b1"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@eda9cd31750e687d990839e38007251aa3fe08b1",
"title": "eda9cd31750e687d990839e38007251aa3fe08b1",
"url": "https://fuchsia.googlesource.com/fuchsia/+/eda9cd31750e687d990839e38007251aa3fe08b1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "367ba5df2ae6d16e9ea470fedc305700512b900b"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@367ba5df2ae6d16e9ea470fedc305700512b900b",
"title": "367ba5df2ae6d16e9ea470fedc305700512b900b",
"url": "https://fuchsia.googlesource.com/fuchsia/+/367ba5df2ae6d16e9ea470fedc305700512b900b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "1ba5ab1cf2657d249c613e45bf2474aea3589f38"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@1ba5ab1cf2657d249c613e45bf2474aea3589f38",
"title": "1ba5ab1cf2657d249c613e45bf2474aea3589f38",
"url": "https://fuchsia.googlesource.com/fuchsia/+/1ba5ab1cf2657d249c613e45bf2474aea3589f38"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "03b41519391d4f7481b408a900e1ba696e2d863a"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@03b41519391d4f7481b408a900e1ba696e2d863a",
"title": "03b41519391d4f7481b408a900e1ba696e2d863a",
"url": "https://fuchsia.googlesource.com/fuchsia/+/03b41519391d4f7481b408a900e1ba696e2d863a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "fae00db2dad0ca90fb3d62a193e67f3abd6732be"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@fae00db2dad0ca90fb3d62a193e67f3abd6732be",
"title": "fae00db2dad0ca90fb3d62a193e67f3abd6732be",
"url": "https://fuchsia.googlesource.com/fuchsia/+/fae00db2dad0ca90fb3d62a193e67f3abd6732be"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "15f440bb9243acf35d96a59d5140f5161c021bc7"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@15f440bb9243acf35d96a59d5140f5161c021bc7",
"title": "15f440bb9243acf35d96a59d5140f5161c021bc7",
"url": "https://fuchsia.googlesource.com/fuchsia/+/15f440bb9243acf35d96a59d5140f5161c021bc7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "59996babb7fe129a8a82bbfed012da7d3a05256c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@59996babb7fe129a8a82bbfed012da7d3a05256c",
"title": "59996babb7fe129a8a82bbfed012da7d3a05256c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/59996babb7fe129a8a82bbfed012da7d3a05256c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "e1311bce910ea5026dd9bab52b756f9a4bccd8a3"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@e1311bce910ea5026dd9bab52b756f9a4bccd8a3",
"title": "e1311bce910ea5026dd9bab52b756f9a4bccd8a3",
"url": "https://fuchsia.googlesource.com/fuchsia/+/e1311bce910ea5026dd9bab52b756f9a4bccd8a3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "f24246b86c65a594b06929d5f5ab3d10ad13c0e7"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@f24246b86c65a594b06929d5f5ab3d10ad13c0e7",
"title": "f24246b86c65a594b06929d5f5ab3d10ad13c0e7",
"url": "https://fuchsia.googlesource.com/fuchsia/+/f24246b86c65a594b06929d5f5ab3d10ad13c0e7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "014f0e28c3f3f9378c7ed4fcd2971cbcc1e9dfa4"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@014f0e28c3f3f9378c7ed4fcd2971cbcc1e9dfa4",
"title": "014f0e28c3f3f9378c7ed4fcd2971cbcc1e9dfa4",
"url": "https://fuchsia.googlesource.com/fuchsia/+/014f0e28c3f3f9378c7ed4fcd2971cbcc1e9dfa4"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "014f0e28c3f3f9378c7ed4fcd2971cbcc1e9dfa4",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8913458302243385168"
},
{
"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:06:10.888] Scheduled build:
{
"id": "8748028240202450657",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-13T19:06:10.330676841Z",
"updateTime": "2024-05-13T19:06:10.330676841Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "014f0e28c3f3f9378c7ed4fcd2971cbcc1e9dfa4",
"ref": "refs/heads/main"
}
}
}
[19:06:10.888] Task URL: https://cr-buildbucket.appspot.com/build/8748028240202450657
[19:06:10.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:2:0) after 9m53s
[19:06:11.116] Received PubSub notification, asking Buildbucket for the build status
[19:06:11.168] Build status: SCHEDULED
[19:08:23.004] Received PubSub notification, asking Buildbucket for the build status
[19:08:23.023] Build status: STARTED
[19:16:03.913] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:2:0)
[19:16:03.966] Build status: STARTED
[19:16:03.966] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:5:0) after 6m2s
[19:22:06.277] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:5:0)
[19:22:06.297] Build status: STARTED
[19:22:06.297] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:6:0) after 5m20s
[19:27:26.416] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:6:0)
[19:27:26.435] Build status: STARTED
[19:27:26.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:7:0) after 4m8s
[19:31:34.490] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:7:0)
[19:31:34.514] Build status: STARTED
[19:31:34.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:8:0) after 9m29s
[19:41:03.551] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:8:0)
[19:41:03.570] Build status: STARTED
[19:41:03.570] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:9:0) after 9m30s
[19:50:33.597] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:9:0)
[19:50:33.654] Build status: STARTED
[19:50:33.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:10:0) after 5m29s
[19:56:02.675] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:10:0)
[19:56:02.728] Build status: STARTED
[19:56:02.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:11:0) after 9m37s
[20:05:39.756] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:11:0)
[20:05:39.783] Build status: STARTED
[20:05:39.783] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:12:0) after 5m21s
[20:11:00.799] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:12:0)
[20:11:00.816] Build status: STARTED
[20:11:00.816] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:13:0) after 7m34s
[20:18:34.867] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:13:0)
[20:18:34.892] Build status: STARTED
[20:18:34.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:14:0) after 8m38s
[20:27:12.910] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:14:0)
[20:27:12.939] Build status: STARTED
[20:27:12.939] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:15:0) after 9m32s
[20:36:44.959] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:15:0)
[20:36:44.987] Build status: STARTED
[20:36:44.987] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:16:0) after 2m31s
[20:39:16.066] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:16:0)
[20:39:16.097] Build status: STARTED
[20:39:16.097] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:17:0) after 3m31s
[20:42:47.119] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:17:0)
[20:42:47.138] Build status: STARTED
[20:42:47.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:18:0) after 1m30s
[20:44:17.158] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:18:0)
[20:44:17.368] Build status: STARTED
[20:44:17.368] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:19:0) after 2m45s
[20:47:02.341] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:19:0)
[20:47:02.456] Build status: STARTED
[20:47:02.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:20:0) after 3m14s
[20:50:16.478] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:20:0)
[20:50:16.647] Build status: STARTED
[20:50:16.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:21:0) after 4m48s
[20:55:04.671] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:21:0)
[20:55:04.695] Build status: STARTED
[20:55:04.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:22:0) after 9m5s
[21:04:09.760] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:22:0)
[21:04:09.792] Build status: STARTED
[21:04:09.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:23:0) after 6m5s
[21:10:14.837] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:23:0)
[21:10:14.855] Build status: STARTED
[21:10:14.855] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:24:0) after 7m42s
[21:17:57.027] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:24:0)
[21:17:57.060] Build status: STARTED
[21:17:57.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:25:0) after 2m39s
[21:20:36.078] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:25:0)
[21:20:36.099] Build status: STARTED
[21:20:36.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:26:0) after 4m37s
[21:25:13.166] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:26:0)
[21:25:13.186] Build status: STARTED
[21:25:13.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913458302243385168:27:0) after 1m55s
[21:26:23.451] Received PubSub notification, asking Buildbucket for the build status
[21:26:23.561] Build:
{
"id": "8748028240202450657",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-13T19:06:10.330676841Z",
"startTime": "2024-05-13T19:08:22.490398205Z",
"endTime": "2024-05-13T21:26:22.855759651Z",
"updateTime": "2024-05-13T21:26:22.855759651Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "014f0e28c3f3f9378c7ed4fcd2971cbcc1e9dfa4",
"ref": "refs/heads/main"
}
}
}
[21:26:23.561] Invocation finished in 2h20m14.560798004s with status FAILED