[15:19:33.033] New invocation is queued and will start shortly
[15:19:34.179] Starting the invocation (attempt 1)
[15:19:34.215] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/6ef2467fab6a602eae4bcfdf7a9bd8192cb97838"
[15:19:34.215] Popped gitiles commit info from properties and tags
[15:19:34.215] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:19:34.215] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:19:34.216] Buildbucket request:
{
"requestId": "8943550751503735408",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-vulkan_cts"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8943550751503735408",
"job": "fuchsia/core.x64-release-vulkan_cts-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0922bea2daaa645a5d8559329968fae7fb01e2f6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0922bea2daaa645a5d8559329968fae7fb01e2f6",
"title": "0922bea2daaa645a5d8559329968fae7fb01e2f6",
"url": "https://fuchsia.googlesource.com/integration/+/0922bea2daaa645a5d8559329968fae7fb01e2f6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "802630ecee5048e108e68fb4989f9b12cf6bc75e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@802630ecee5048e108e68fb4989f9b12cf6bc75e",
"title": "802630ecee5048e108e68fb4989f9b12cf6bc75e",
"url": "https://fuchsia.googlesource.com/integration/+/802630ecee5048e108e68fb4989f9b12cf6bc75e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "12829d742932f243f8dcc20b787b536246b8133c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@12829d742932f243f8dcc20b787b536246b8133c",
"title": "12829d742932f243f8dcc20b787b536246b8133c",
"url": "https://fuchsia.googlesource.com/integration/+/12829d742932f243f8dcc20b787b536246b8133c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2d19f9ef680652f957a88515cfe6caa7c6115a81"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2d19f9ef680652f957a88515cfe6caa7c6115a81",
"title": "2d19f9ef680652f957a88515cfe6caa7c6115a81",
"url": "https://fuchsia.googlesource.com/integration/+/2d19f9ef680652f957a88515cfe6caa7c6115a81"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0bafbde46b7acd4f9bdc4f4bf8ba4b28ecfec481"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0bafbde46b7acd4f9bdc4f4bf8ba4b28ecfec481",
"title": "0bafbde46b7acd4f9bdc4f4bf8ba4b28ecfec481",
"url": "https://fuchsia.googlesource.com/integration/+/0bafbde46b7acd4f9bdc4f4bf8ba4b28ecfec481"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6ef2467fab6a602eae4bcfdf7a9bd8192cb97838"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6ef2467fab6a602eae4bcfdf7a9bd8192cb97838",
"title": "6ef2467fab6a602eae4bcfdf7a9bd8192cb97838",
"url": "https://fuchsia.googlesource.com/integration/+/6ef2467fab6a602eae4bcfdf7a9bd8192cb97838"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6ef2467fab6a602eae4bcfdf7a9bd8192cb97838",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8943550751503735408"
},
{
"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"
}
}
[15:19:35.010] Scheduled build:
{
"id": "8778120689529653025",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-vulkan_cts"
},
"createdBy": "project:fuchsia",
"createTime": "2023-06-16T15:19:34.289262944Z",
"updateTime": "2023-06-16T15:19:34.289262944Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6ef2467fab6a602eae4bcfdf7a9bd8192cb97838",
"ref": "refs/heads/main"
}
}
}
[15:19:35.010] Task URL: https://cr-buildbucket.appspot.com/build/8778120689529653025
[15:19:35.010] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:2:0) after 4m55s
[15:19:43.095] Received PubSub notification, asking Buildbucket for the build status
[15:19:43.119] Build status: STARTED
[15:24:30.105] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:2:0)
[15:24:30.124] Build status: STARTED
[15:24:30.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:4:0) after 7m56s
[15:32:26.659] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:4:0)
[15:32:26.679] Build status: STARTED
[15:32:26.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:5:0) after 3m4s
[15:35:30.800] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:5:0)
[15:35:30.820] Build status: STARTED
[15:35:30.820] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:6:0) after 5m22s
[15:40:53.014] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:6:0)
[15:40:53.102] Build status: STARTED
[15:40:53.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:7:0) after 3m27s
[15:44:20.123] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:7:0)
[15:44:20.334] Build status: STARTED
[15:44:20.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:8:0) after 8m30s
[15:52:50.351] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:8:0)
[15:52:50.370] Build status: STARTED
[15:52:50.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:9:0) after 7m47s
[16:00:37.536] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:9:0)
[16:00:37.557] Build status: STARTED
[16:00:37.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:10:0) after 1m15s
[16:01:52.733] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:10:0)
[16:01:52.789] Build status: STARTED
[16:01:52.789] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:11:0) after 4m19s
[16:06:12.354] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:11:0)
[16:06:12.378] Build status: STARTED
[16:06:12.378] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:12:0) after 4m39s
[16:10:51.674] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:12:0)
[16:10:51.703] Build status: STARTED
[16:10:51.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:13:0) after 6m13s
[16:17:04.922] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:13:0)
[16:17:04.987] Build status: STARTED
[16:17:04.987] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:14:0) after 1m9s
[16:18:14.011] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:14:0)
[16:18:14.043] Build status: STARTED
[16:18:14.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:15:0) after 1m2s
[16:19:16.132] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:15:0)
[16:19:16.150] Build status: STARTED
[16:19:16.150] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:16:0) after 6m42s
[16:25:59.181] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:16:0)
[16:25:59.200] Build status: STARTED
[16:25:59.200] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:17:0) after 6m2s
[16:32:01.327] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:17:0)
[16:32:01.345] Build status: STARTED
[16:32:01.345] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:18:0) after 5m2s
[16:37:03.383] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:18:0)
[16:37:03.414] Build status: STARTED
[16:37:03.414] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:19:0) after 1m50s
[16:38:53.436] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:19:0)
[16:38:53.455] Build status: STARTED
[16:38:53.455] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:20:0) after 5m36s
[16:44:29.486] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:20:0)
[16:44:29.510] Build status: STARTED
[16:44:29.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943550751503735408:21:0) after 6m6s
[16:49:16.729] Received PubSub notification, asking Buildbucket for the build status
[16:49:16.750] Build:
{
"id": "8778120689529653025",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-vulkan_cts"
},
"createdBy": "project:fuchsia",
"createTime": "2023-06-16T15:19:34.289262944Z",
"startTime": "2023-06-16T15:19:42.264573Z",
"endTime": "2023-06-16T16:49:16.474767870Z",
"updateTime": "2023-06-16T16:49:16.474767870Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6ef2467fab6a602eae4bcfdf7a9bd8192cb97838",
"ref": "refs/heads/main"
}
}
}
[16:49:16.750] Invocation finished in 1h29m43.726910451s with status FAILED