[04:16:56.632] New invocation is queued and will start shortly
[04:16:57.878] Starting the invocation (attempt 1)
[04:16:57.902] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/b55f04b699c399f8bc39678d8d458e213f994fee"
[04:16:57.902] Popped gitiles commit info from properties and tags
[04:16:57.902] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:16:57.902] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:16:57.903] Buildbucket request:
{
"requestId": "8939243784726076464",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-vulkan_cts"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8939243784726076464",
"job": "fuchsia/core.x64-release-vulkan_cts-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a395b922af1b1a80eb7d38b24d5e1a508780d1b0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a395b922af1b1a80eb7d38b24d5e1a508780d1b0",
"title": "a395b922af1b1a80eb7d38b24d5e1a508780d1b0",
"url": "https://fuchsia.googlesource.com/integration/+/a395b922af1b1a80eb7d38b24d5e1a508780d1b0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "56573adcb92e3ce48f1d1f28d20dd372d8d4e51c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@56573adcb92e3ce48f1d1f28d20dd372d8d4e51c",
"title": "56573adcb92e3ce48f1d1f28d20dd372d8d4e51c",
"url": "https://fuchsia.googlesource.com/integration/+/56573adcb92e3ce48f1d1f28d20dd372d8d4e51c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "50b5df80872374586a7407dad850aca8fdc137ea"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@50b5df80872374586a7407dad850aca8fdc137ea",
"title": "50b5df80872374586a7407dad850aca8fdc137ea",
"url": "https://fuchsia.googlesource.com/integration/+/50b5df80872374586a7407dad850aca8fdc137ea"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a1af663927d145ec704ae278b8145e45ddcaf40b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a1af663927d145ec704ae278b8145e45ddcaf40b",
"title": "a1af663927d145ec704ae278b8145e45ddcaf40b",
"url": "https://fuchsia.googlesource.com/integration/+/a1af663927d145ec704ae278b8145e45ddcaf40b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4d598f0db6bc12a170d1e870831c4c939d153d00"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4d598f0db6bc12a170d1e870831c4c939d153d00",
"title": "4d598f0db6bc12a170d1e870831c4c939d153d00",
"url": "https://fuchsia.googlesource.com/integration/+/4d598f0db6bc12a170d1e870831c4c939d153d00"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a598e75287464c1d6d1e825d1b92db734d893b1d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a598e75287464c1d6d1e825d1b92db734d893b1d",
"title": "a598e75287464c1d6d1e825d1b92db734d893b1d",
"url": "https://fuchsia.googlesource.com/integration/+/a598e75287464c1d6d1e825d1b92db734d893b1d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b55f04b699c399f8bc39678d8d458e213f994fee"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b55f04b699c399f8bc39678d8d458e213f994fee",
"title": "b55f04b699c399f8bc39678d8d458e213f994fee",
"url": "https://fuchsia.googlesource.com/integration/+/b55f04b699c399f8bc39678d8d458e213f994fee"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b55f04b699c399f8bc39678d8d458e213f994fee",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8939243784726076464"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-release-vulkan_cts-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:16:58.508] Scheduled build:
{
"id": "8773813722682780209",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-vulkan_cts"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-03T04:16:57.952965472Z",
"updateTime": "2023-08-03T04:16:57.952965472Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b55f04b699c399f8bc39678d8d458e213f994fee",
"ref": "refs/heads/main"
}
}
}
[04:16:58.508] Task URL: https://cr-buildbucket.appspot.com/build/8773813722682780209
[04:16:58.508] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:2:0) after 5m48s
[04:16:59.339] Received PubSub notification, asking Buildbucket for the build status
[04:16:59.365] Build status: SCHEDULED
[04:17:05.082] Received PubSub notification, asking Buildbucket for the build status
[04:17:05.103] Build status: STARTED
[04:22:46.689] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:2:0)
[04:22:46.736] Build status: STARTED
[04:22:46.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:5:0) after 6m31s
[04:29:18.170] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:5:0)
[04:29:18.192] Build status: STARTED
[04:29:18.192] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:6:0) after 5m44s
[04:35:02.626] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:6:0)
[04:35:02.697] Build status: STARTED
[04:35:02.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:7:0) after 9m36s
[04:44:38.803] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:7:0)
[04:44:38.821] Build status: STARTED
[04:44:38.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:8:0) after 2m17s
[04:46:55.856] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:8:0)
[04:46:55.885] Build status: STARTED
[04:46:55.885] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:9:0) after 1m16s
[04:48:12.958] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:9:0)
[04:48:12.981] Build status: STARTED
[04:48:12.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:10:0) after 6m23s
[04:54:35.998] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:10:0)
[04:54:36.038] Build status: STARTED
[04:54:36.038] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:11:0) after 1m29s
[04:56:05.079] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:11:0)
[04:56:05.099] Build status: STARTED
[04:56:05.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:12:0) after 4m34s
[05:00:39.177] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:12:0)
[05:00:39.251] Build status: STARTED
[05:00:39.251] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:13:0) after 1m46s
[05:02:25.268] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:13:0)
[05:02:25.322] Build status: STARTED
[05:02:25.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:14:0) after 1m4s
[05:03:29.338] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:14:0)
[05:03:29.357] Build status: STARTED
[05:03:29.357] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:15:0) after 2m59s
[05:06:28.370] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:15:0)
[05:06:28.390] Build status: STARTED
[05:06:28.390] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:16:0) after 4m25s
[05:10:53.404] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:16:0)
[05:10:53.425] Build status: STARTED
[05:10:53.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:17:0) after 1m47s
[05:12:40.441] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:17:0)
[05:12:40.459] Build status: STARTED
[05:12:40.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:18:0) after 2m40s
[05:15:21.284] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:18:0)
[05:15:21.303] Build status: STARTED
[05:15:21.303] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:19:0) after 6m5s
[05:21:26.356] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:19:0)
[05:21:26.375] Build status: STARTED
[05:21:26.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:20:0) after 8m24s
[05:29:50.429] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:20:0)
[05:29:50.492] Build status: STARTED
[05:29:50.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:21:0) after 5m1s
[05:34:53.103] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:21:0)
[05:34:53.125] Build status: STARTED
[05:34:53.125] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:22:0) after 3m41s
[05:38:34.101] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:22:0)
[05:38:34.119] Build status: STARTED
[05:38:34.119] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:23:0) after 5m2s
[05:43:36.200] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:23:0)
[05:43:36.215] Build status: STARTED
[05:43:36.216] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8939243784726076464:24:0) after 5m5s
[05:45:59.634] Received PubSub notification, asking Buildbucket for the build status
[05:45:59.713] Build:
{
"id": "8773813722682780209",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-vulkan_cts"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-03T04:16:57.952965472Z",
"startTime": "2023-08-03T04:17:03.129902Z",
"endTime": "2023-08-03T05:45:59.330577753Z",
"updateTime": "2023-08-03T05:45:59.330577753Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b55f04b699c399f8bc39678d8d458e213f994fee",
"ref": "refs/heads/main"
}
}
}
[05:45:59.713] Invocation finished in 1h29m3.093887672s with status FAILED