[05:10:53.071] New invocation is queued and will start shortly
[05:10:54.180] Starting the invocation (attempt 1)
[05:10:54.206] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/8fc3587d25a3565ce1493de2c833db327dbd68cf"
[05:10:54.206] Popped gitiles commit info from properties and tags
[05:10:54.206] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:10:54.206] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:10:54.206] Buildbucket request:
{
"requestId": "8901823843952823264",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8901823843952823264",
"job": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "db66172f8c2ab9acc355a04ef225385b304d3c46"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@db66172f8c2ab9acc355a04ef225385b304d3c46",
"title": "db66172f8c2ab9acc355a04ef225385b304d3c46",
"url": "https://fuchsia.googlesource.com/fuchsia/+/db66172f8c2ab9acc355a04ef225385b304d3c46"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "06369c53394889ae3eb9a3a2094ad6258b2bf98b"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@06369c53394889ae3eb9a3a2094ad6258b2bf98b",
"title": "06369c53394889ae3eb9a3a2094ad6258b2bf98b",
"url": "https://fuchsia.googlesource.com/fuchsia/+/06369c53394889ae3eb9a3a2094ad6258b2bf98b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "108628e4270a7ae3e3eac78ba1ce56ff254e5889"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@108628e4270a7ae3e3eac78ba1ce56ff254e5889",
"title": "108628e4270a7ae3e3eac78ba1ce56ff254e5889",
"url": "https://fuchsia.googlesource.com/fuchsia/+/108628e4270a7ae3e3eac78ba1ce56ff254e5889"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6b80732a200e0e1b9c5f7de61bcee8418c761529"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6b80732a200e0e1b9c5f7de61bcee8418c761529",
"title": "6b80732a200e0e1b9c5f7de61bcee8418c761529",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6b80732a200e0e1b9c5f7de61bcee8418c761529"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "110bac45dfa1109c084ba7e26dbca0ecb7834ad6"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@110bac45dfa1109c084ba7e26dbca0ecb7834ad6",
"title": "110bac45dfa1109c084ba7e26dbca0ecb7834ad6",
"url": "https://fuchsia.googlesource.com/fuchsia/+/110bac45dfa1109c084ba7e26dbca0ecb7834ad6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "0fedeace56b94bbe6dbea98581eebc9bbe89487f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@0fedeace56b94bbe6dbea98581eebc9bbe89487f",
"title": "0fedeace56b94bbe6dbea98581eebc9bbe89487f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/0fedeace56b94bbe6dbea98581eebc9bbe89487f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "16e120a43237804e55816ebac28ed3624a7ebfa2"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@16e120a43237804e55816ebac28ed3624a7ebfa2",
"title": "16e120a43237804e55816ebac28ed3624a7ebfa2",
"url": "https://fuchsia.googlesource.com/fuchsia/+/16e120a43237804e55816ebac28ed3624a7ebfa2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "551b98ea83d7259544144200eb6733ccab700c4f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@551b98ea83d7259544144200eb6733ccab700c4f",
"title": "551b98ea83d7259544144200eb6733ccab700c4f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/551b98ea83d7259544144200eb6733ccab700c4f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8fc3587d25a3565ce1493de2c833db327dbd68cf"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8fc3587d25a3565ce1493de2c833db327dbd68cf",
"title": "8fc3587d25a3565ce1493de2c833db327dbd68cf",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8fc3587d25a3565ce1493de2c833db327dbd68cf"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "8fc3587d25a3565ce1493de2c833db327dbd68cf",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8901823843952823264"
},
{
"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"
}
}
[05:10:54.778] Scheduled build:
{
"id": "8736393782029346673",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-19T05:10:54.275167714Z",
"updateTime": "2024-09-19T05:10:54.275167714Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "8fc3587d25a3565ce1493de2c833db327dbd68cf",
"ref": "refs/heads/main"
}
}
}
[05:10:54.778] Task URL: https://cr-buildbucket.appspot.com/build/8736393782029346673
[05:10:54.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:2:0) after 1m13s
[05:10:55.689] Received PubSub notification, asking Buildbucket for the build status
[05:10:55.708] Build status: SCHEDULED
[05:11:00.343] Received PubSub notification, asking Buildbucket for the build status
[05:11:00.364] Build status: STARTED
[05:12:07.793] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:2:0)
[05:12:08.142] Build status: STARTED
[05:12:08.142] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:5:0) after 9m34s
[05:21:42.258] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:5:0)
[05:21:42.282] Build status: STARTED
[05:21:42.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:6:0) after 9m52s
[05:31:34.339] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:6:0)
[05:31:34.388] Build status: STARTED
[05:31:34.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:7:0) after 2m56s
[05:34:30.424] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:7:0)
[05:34:30.444] Build status: STARTED
[05:34:30.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:8:0) after 4m14s
[05:38:44.868] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:8:0)
[05:38:44.889] Build status: STARTED
[05:38:44.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:9:0) after 3m13s
[05:41:57.907] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:9:0)
[05:41:57.934] Build status: STARTED
[05:41:57.934] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:10:0) after 7m13s
[05:49:11.053] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:10:0)
[05:49:11.070] Build status: STARTED
[05:49:11.071] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:11:0) after 8m17s
[05:57:28.204] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:11:0)
[05:57:28.221] Build status: STARTED
[05:57:28.221] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:12:0) after 4m33s
[06:02:01.373] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:12:0)
[06:02:01.390] Build status: STARTED
[06:02:01.390] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:13:0) after 5m44s
[06:07:45.802] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:13:0)
[06:07:45.821] Build status: STARTED
[06:07:45.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:14:0) after 5m1s
[06:12:46.838] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:14:0)
[06:12:46.858] Build status: STARTED
[06:12:46.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:15:0) after 6m45s
[06:19:31.955] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:15:0)
[06:19:31.976] Build status: STARTED
[06:19:31.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:16:0) after 2m8s
[06:21:40.040] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:16:0)
[06:21:40.062] Build status: STARTED
[06:21:40.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:17:0) after 2m3s
[06:23:43.224] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:17:0)
[06:23:43.253] Build status: STARTED
[06:23:43.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:18:0) after 2m22s
[06:26:05.956] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:18:0)
[06:26:05.976] Build status: STARTED
[06:26:05.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:19:0) after 8m23s
[06:34:29.418] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:19:0)
[06:34:29.444] Build status: STARTED
[06:34:29.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:20:0) after 8m49s
[06:43:18.582] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:20:0)
[06:43:18.603] Build status: STARTED
[06:43:18.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901823843952823264:21:0) after 2m30s
[06:44:32.528] Received PubSub notification, asking Buildbucket for the build status
[06:44:32.545] Build:
{
"id": "8736393782029346673",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-19T05:10:54.275167714Z",
"startTime": "2024-09-19T05:11:00.151351221Z",
"endTime": "2024-09-19T06:44:31.068686757Z",
"updateTime": "2024-09-19T06:44:31.068686757Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "8fc3587d25a3565ce1493de2c833db327dbd68cf",
"ref": "refs/heads/main"
}
}
}
[06:44:32.545] Invocation finished in 1h33m39.488437714s with status FAILED