[02:04:07.305] New invocation is queued and will start shortly
[02:04:08.540] Starting the invocation (attempt 1)
[02:04:08.568] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/6053ee35190fb13e6dba854e04de90042c5aeb58"
[02:04:08.568] Popped gitiles commit info from properties and tags
[02:04:08.568] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:04:08.569] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:04:08.570] Buildbucket request:
{
"requestId": "8902469772805037328",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8902469772805037328",
"job": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "f8201b79a86a1311f40ef18d6ae598007b3d4991"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@f8201b79a86a1311f40ef18d6ae598007b3d4991",
"title": "f8201b79a86a1311f40ef18d6ae598007b3d4991",
"url": "https://fuchsia.googlesource.com/fuchsia/+/f8201b79a86a1311f40ef18d6ae598007b3d4991"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "f108974de4f91bd9382a16e6275a8f1390623cb5"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@f108974de4f91bd9382a16e6275a8f1390623cb5",
"title": "f108974de4f91bd9382a16e6275a8f1390623cb5",
"url": "https://fuchsia.googlesource.com/fuchsia/+/f108974de4f91bd9382a16e6275a8f1390623cb5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "fe3fa754f96943c02d05a99f4c908ef486c379f7"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@fe3fa754f96943c02d05a99f4c908ef486c379f7",
"title": "fe3fa754f96943c02d05a99f4c908ef486c379f7",
"url": "https://fuchsia.googlesource.com/fuchsia/+/fe3fa754f96943c02d05a99f4c908ef486c379f7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "da23bdc61e60a40166631536c9d1349a1c01475d"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@da23bdc61e60a40166631536c9d1349a1c01475d",
"title": "da23bdc61e60a40166631536c9d1349a1c01475d",
"url": "https://fuchsia.googlesource.com/fuchsia/+/da23bdc61e60a40166631536c9d1349a1c01475d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "3a2ed53c744716aa145c978510849c9cbadd488e"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@3a2ed53c744716aa145c978510849c9cbadd488e",
"title": "3a2ed53c744716aa145c978510849c9cbadd488e",
"url": "https://fuchsia.googlesource.com/fuchsia/+/3a2ed53c744716aa145c978510849c9cbadd488e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "00836c8f870a2e6013072b01fd2d393b765770bd"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@00836c8f870a2e6013072b01fd2d393b765770bd",
"title": "00836c8f870a2e6013072b01fd2d393b765770bd",
"url": "https://fuchsia.googlesource.com/fuchsia/+/00836c8f870a2e6013072b01fd2d393b765770bd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "394c1de4c124c2a1b8544cc126e2bb12828765ca"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@394c1de4c124c2a1b8544cc126e2bb12828765ca",
"title": "394c1de4c124c2a1b8544cc126e2bb12828765ca",
"url": "https://fuchsia.googlesource.com/fuchsia/+/394c1de4c124c2a1b8544cc126e2bb12828765ca"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "944a4313095a5d882944f6e686981e7c266eebb8"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@944a4313095a5d882944f6e686981e7c266eebb8",
"title": "944a4313095a5d882944f6e686981e7c266eebb8",
"url": "https://fuchsia.googlesource.com/fuchsia/+/944a4313095a5d882944f6e686981e7c266eebb8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "30fc40f193b8334c3afeda59f9fec393cd69bc2d"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@30fc40f193b8334c3afeda59f9fec393cd69bc2d",
"title": "30fc40f193b8334c3afeda59f9fec393cd69bc2d",
"url": "https://fuchsia.googlesource.com/fuchsia/+/30fc40f193b8334c3afeda59f9fec393cd69bc2d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8cb7e1dd173f413273315f43285a66a9e4f10aa2"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8cb7e1dd173f413273315f43285a66a9e4f10aa2",
"title": "8cb7e1dd173f413273315f43285a66a9e4f10aa2",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8cb7e1dd173f413273315f43285a66a9e4f10aa2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "23868ae9bcf0b7a345b678ba9d3357594d85e3f0"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@23868ae9bcf0b7a345b678ba9d3357594d85e3f0",
"title": "23868ae9bcf0b7a345b678ba9d3357594d85e3f0",
"url": "https://fuchsia.googlesource.com/fuchsia/+/23868ae9bcf0b7a345b678ba9d3357594d85e3f0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "b751f62946cd6727cbc2e0f17e4c11ab15170c7b"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@b751f62946cd6727cbc2e0f17e4c11ab15170c7b",
"title": "b751f62946cd6727cbc2e0f17e4c11ab15170c7b",
"url": "https://fuchsia.googlesource.com/fuchsia/+/b751f62946cd6727cbc2e0f17e4c11ab15170c7b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "306757ca37efcffe72d7750784086c0b850a17ab"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@306757ca37efcffe72d7750784086c0b850a17ab",
"title": "306757ca37efcffe72d7750784086c0b850a17ab",
"url": "https://fuchsia.googlesource.com/fuchsia/+/306757ca37efcffe72d7750784086c0b850a17ab"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "302b3a97f4318e9e3ad68dfad67c77053f7eb87b"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@302b3a97f4318e9e3ad68dfad67c77053f7eb87b",
"title": "302b3a97f4318e9e3ad68dfad67c77053f7eb87b",
"url": "https://fuchsia.googlesource.com/fuchsia/+/302b3a97f4318e9e3ad68dfad67c77053f7eb87b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6053ee35190fb13e6dba854e04de90042c5aeb58"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6053ee35190fb13e6dba854e04de90042c5aeb58",
"title": "6053ee35190fb13e6dba854e04de90042c5aeb58",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6053ee35190fb13e6dba854e04de90042c5aeb58"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "6053ee35190fb13e6dba854e04de90042c5aeb58",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8902469772805037328"
},
{
"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"
}
}
[02:04:09.098] Scheduled build:
{
"id": "8737039710748505265",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-12T02:04:08.645254736Z",
"updateTime": "2024-09-12T02:04:08.645254736Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "6053ee35190fb13e6dba854e04de90042c5aeb58",
"ref": "refs/heads/main"
}
}
}
[02:04:09.098] Task URL: https://cr-buildbucket.appspot.com/build/8737039710748505265
[02:04:09.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:2:0) after 3m18s
[02:04:10.066] Received PubSub notification, asking Buildbucket for the build status
[02:04:10.102] Build status: SCHEDULED
[02:04:14.793] Received PubSub notification, asking Buildbucket for the build status
[02:04:14.827] Build status: STARTED
[02:07:27.118] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:2:0)
[02:07:27.145] Build status: STARTED
[02:07:27.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:5:0) after 9m30s
[02:16:57.167] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:5:0)
[02:16:57.189] Build status: STARTED
[02:16:57.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:6:0) after 9m27s
[02:26:24.265] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:6:0)
[02:26:24.283] Build status: STARTED
[02:26:24.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:7:0) after 2m10s
[02:28:34.347] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:7:0)
[02:28:34.369] Build status: STARTED
[02:28:34.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:8:0) after 2m36s
[02:31:10.531] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:8:0)
[02:31:10.576] Build status: STARTED
[02:31:10.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:9:0) after 4m56s
[02:36:06.605] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:9:0)
[02:36:06.641] Build status: STARTED
[02:36:06.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:10:0) after 8m52s
[02:44:59.002] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:10:0)
[02:44:59.029] Build status: STARTED
[02:44:59.029] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:11:0) after 1m24s
[02:46:23.147] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:11:0)
[02:46:23.169] Build status: STARTED
[02:46:23.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:12:0) after 4m2s
[02:50:25.352] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:12:0)
[02:50:25.370] Build status: STARTED
[02:50:25.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:13:0) after 7m11s
[02:57:36.389] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:13:0)
[02:57:36.410] Build status: STARTED
[02:57:36.410] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:14:0) after 3m5s
[03:00:41.468] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:14:0)
[03:00:41.503] Build status: STARTED
[03:00:41.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:15:0) after 4m0s
[03:04:41.768] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:15:0)
[03:04:41.797] Build status: STARTED
[03:04:41.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:16:0) after 7m27s
[03:12:08.868] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:16:0)
[03:12:08.888] Build status: STARTED
[03:12:08.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:17:0) after 7m40s
[03:19:48.943] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:17:0)
[03:19:48.962] Build status: STARTED
[03:19:48.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:18:0) after 3m46s
[03:23:34.983] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:18:0)
[03:23:35.004] Build status: STARTED
[03:23:35.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:19:0) after 7m16s
[03:30:51.030] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:19:0)
[03:30:51.048] Build status: STARTED
[03:30:51.048] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:20:0) after 2m1s
[03:32:52.073] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:20:0)
[03:32:52.091] Build status: STARTED
[03:32:52.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:21:0) after 5m14s
[03:38:06.114] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:21:0)
[03:38:06.133] Build status: STARTED
[03:38:06.133] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:22:0) after 9m55s
[03:48:01.187] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:22:0)
[03:48:01.209] Build status: STARTED
[03:48:01.209] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:23:0) after 4m51s
[03:52:52.248] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:23:0)
[03:52:52.267] Build status: STARTED
[03:52:52.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8902469772805037328:24:0) after 2m34s
[03:53:54.384] Received PubSub notification, asking Buildbucket for the build status
[03:53:54.402] Build:
{
"id": "8737039710748505265",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-12T02:04:08.645254736Z",
"startTime": "2024-09-12T02:04:14.569970068Z",
"endTime": "2024-09-12T03:53:53.793827819Z",
"updateTime": "2024-09-12T03:53:53.793827819Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "6053ee35190fb13e6dba854e04de90042c5aeb58",
"ref": "refs/heads/main"
}
}
}
[03:53:54.402] Invocation finished in 1h49m47.101572922s with status FAILED