[18:15:54.796] New invocation is queued and will start shortly
[18:15:55.990] Starting the invocation (attempt 1)
[18:15:56.019] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/0b6714c2f6d39eaf595c6c9d9bc7be5cab009c58"
[18:15:56.019] Popped gitiles commit info from properties and tags
[18:15:56.019] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:15:56.019] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:15:56.022] Buildbucket request:
{
"requestId": "8943086670890379456",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-vulkan_cts"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8943086670890379456",
"job": "fuchsia/core.x64-release-vulkan_cts-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "44e9f8d3cbcf20b4c6ad4affeadaee8e90f47561"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@44e9f8d3cbcf20b4c6ad4affeadaee8e90f47561",
"title": "44e9f8d3cbcf20b4c6ad4affeadaee8e90f47561",
"url": "https://fuchsia.googlesource.com/integration/+/44e9f8d3cbcf20b4c6ad4affeadaee8e90f47561"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7a8fc5e2231150c7c66240d5e608f1ed5755a4b2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7a8fc5e2231150c7c66240d5e608f1ed5755a4b2",
"title": "7a8fc5e2231150c7c66240d5e608f1ed5755a4b2",
"url": "https://fuchsia.googlesource.com/integration/+/7a8fc5e2231150c7c66240d5e608f1ed5755a4b2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "296998d09d4a7915351d6f74c881b6bc6dcd4c6c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@296998d09d4a7915351d6f74c881b6bc6dcd4c6c",
"title": "296998d09d4a7915351d6f74c881b6bc6dcd4c6c",
"url": "https://fuchsia.googlesource.com/integration/+/296998d09d4a7915351d6f74c881b6bc6dcd4c6c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "742f9583b1ff9137e4f08130d0e592cb6db93dd1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@742f9583b1ff9137e4f08130d0e592cb6db93dd1",
"title": "742f9583b1ff9137e4f08130d0e592cb6db93dd1",
"url": "https://fuchsia.googlesource.com/integration/+/742f9583b1ff9137e4f08130d0e592cb6db93dd1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a64d0ba62677ecdb64bccfed0c74f0d5b624e9c6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a64d0ba62677ecdb64bccfed0c74f0d5b624e9c6",
"title": "a64d0ba62677ecdb64bccfed0c74f0d5b624e9c6",
"url": "https://fuchsia.googlesource.com/integration/+/a64d0ba62677ecdb64bccfed0c74f0d5b624e9c6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f872c8daf80c2e9482112b3fdc6ddaaa5bf26aec"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f872c8daf80c2e9482112b3fdc6ddaaa5bf26aec",
"title": "f872c8daf80c2e9482112b3fdc6ddaaa5bf26aec",
"url": "https://fuchsia.googlesource.com/integration/+/f872c8daf80c2e9482112b3fdc6ddaaa5bf26aec"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6b986c5edf6e08062eb5a6887fc82517cbf4f7ba"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6b986c5edf6e08062eb5a6887fc82517cbf4f7ba",
"title": "6b986c5edf6e08062eb5a6887fc82517cbf4f7ba",
"url": "https://fuchsia.googlesource.com/integration/+/6b986c5edf6e08062eb5a6887fc82517cbf4f7ba"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3f370003e763366b281e52cf6f19fd8fd285bdc3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3f370003e763366b281e52cf6f19fd8fd285bdc3",
"title": "3f370003e763366b281e52cf6f19fd8fd285bdc3",
"url": "https://fuchsia.googlesource.com/integration/+/3f370003e763366b281e52cf6f19fd8fd285bdc3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d0344f1ae68abf61cea978eba799e44be341094c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d0344f1ae68abf61cea978eba799e44be341094c",
"title": "d0344f1ae68abf61cea978eba799e44be341094c",
"url": "https://fuchsia.googlesource.com/integration/+/d0344f1ae68abf61cea978eba799e44be341094c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "81cc452637f69d96c03bed591da3e64edba1a716"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@81cc452637f69d96c03bed591da3e64edba1a716",
"title": "81cc452637f69d96c03bed591da3e64edba1a716",
"url": "https://fuchsia.googlesource.com/integration/+/81cc452637f69d96c03bed591da3e64edba1a716"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "09f8908d70c17f69b87c73b290b763bf4d8ab048"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@09f8908d70c17f69b87c73b290b763bf4d8ab048",
"title": "09f8908d70c17f69b87c73b290b763bf4d8ab048",
"url": "https://fuchsia.googlesource.com/integration/+/09f8908d70c17f69b87c73b290b763bf4d8ab048"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5a600a49ee1757a0fae9228fde3efdb6289e396e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5a600a49ee1757a0fae9228fde3efdb6289e396e",
"title": "5a600a49ee1757a0fae9228fde3efdb6289e396e",
"url": "https://fuchsia.googlesource.com/integration/+/5a600a49ee1757a0fae9228fde3efdb6289e396e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8c4c3f59c70637b3086a038142aebc3c5c7f6d56"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8c4c3f59c70637b3086a038142aebc3c5c7f6d56",
"title": "8c4c3f59c70637b3086a038142aebc3c5c7f6d56",
"url": "https://fuchsia.googlesource.com/integration/+/8c4c3f59c70637b3086a038142aebc3c5c7f6d56"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bc9d92a058915541ea5af12c6d244e85e5cc45fe"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bc9d92a058915541ea5af12c6d244e85e5cc45fe",
"title": "bc9d92a058915541ea5af12c6d244e85e5cc45fe",
"url": "https://fuchsia.googlesource.com/integration/+/bc9d92a058915541ea5af12c6d244e85e5cc45fe"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0b6714c2f6d39eaf595c6c9d9bc7be5cab009c58"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0b6714c2f6d39eaf595c6c9d9bc7be5cab009c58",
"title": "0b6714c2f6d39eaf595c6c9d9bc7be5cab009c58",
"url": "https://fuchsia.googlesource.com/integration/+/0b6714c2f6d39eaf595c6c9d9bc7be5cab009c58"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "0b6714c2f6d39eaf595c6c9d9bc7be5cab009c58",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8943086670890379456"
},
{
"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"
}
}
[18:15:56.379] Scheduled build:
{
"id": "8777656608883524465",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-vulkan_cts"
},
"createdBy": "project:fuchsia",
"createTime": "2023-06-21T18:15:56.082733739Z",
"updateTime": "2023-06-21T18:15:56.082733739Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "0b6714c2f6d39eaf595c6c9d9bc7be5cab009c58",
"ref": "refs/heads/main"
}
}
}
[18:15:56.379] Task URL: https://cr-buildbucket.appspot.com/build/8777656608883524465
[18:15:56.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:2:0) after 3m0s
[18:16:47.865] Received PubSub notification, asking Buildbucket for the build status
[18:16:47.886] Build status: STARTED
[18:18:56.402] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:2:0)
[18:18:56.439] Build status: STARTED
[18:18:56.439] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:4:0) after 7m27s
[18:26:23.491] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:4:0)
[18:26:23.560] Build status: STARTED
[18:26:23.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:5:0) after 4m14s
[18:30:37.859] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:5:0)
[18:30:37.876] Build status: STARTED
[18:30:37.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:6:0) after 5m31s
[18:36:08.873] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:6:0)
[18:36:08.890] Build status: STARTED
[18:36:08.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:7:0) after 2m8s
[18:38:17.174] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:7:0)
[18:38:17.197] Build status: STARTED
[18:38:17.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:8:0) after 8m40s
[18:46:57.269] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:8:0)
[18:46:57.292] Build status: STARTED
[18:46:57.292] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:9:0) after 7m8s
[18:54:05.267] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:9:0)
[18:54:05.286] Build status: STARTED
[18:54:05.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:10:0) after 6m52s
[19:00:58.023] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:10:0)
[19:00:58.042] Build status: STARTED
[19:00:58.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:11:0) after 6m4s
[19:07:02.061] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:11:0)
[19:07:02.126] Build status: STARTED
[19:07:02.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:12:0) after 4m57s
[19:11:59.211] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:12:0)
[19:11:59.252] Build status: STARTED
[19:11:59.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:13:0) after 2m23s
[19:14:22.526] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:13:0)
[19:14:22.553] Build status: STARTED
[19:14:22.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:14:0) after 6m42s
[19:21:04.884] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:14:0)
[19:21:04.906] Build status: STARTED
[19:21:04.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:15:0) after 8m43s
[19:29:48.232] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:15:0)
[19:29:48.256] Build status: STARTED
[19:29:48.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:16:0) after 1m27s
[19:31:15.624] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:16:0)
[19:31:15.646] Build status: STARTED
[19:31:15.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:17:0) after 7m53s
[19:39:08.991] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:17:0)
[19:39:09.014] Build status: STARTED
[19:39:09.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:18:0) after 5m52s
[19:45:01.055] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:18:0)
[19:45:01.113] Build status: STARTED
[19:45:01.113] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8943086670890379456:19:0) after 9m28s
[19:45:30.627] Received PubSub notification, asking Buildbucket for the build status
[19:45:30.646] Build:
{
"id": "8777656608883524465",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-vulkan_cts"
},
"createdBy": "project:fuchsia",
"createTime": "2023-06-21T18:15:56.082733739Z",
"startTime": "2023-06-21T18:16:47.266823Z",
"endTime": "2023-06-21T19:45:30.262415191Z",
"updateTime": "2023-06-21T19:45:30.262415191Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "0b6714c2f6d39eaf595c6c9d9bc7be5cab009c58",
"ref": "refs/heads/main"
}
}
}
[19:45:30.647] Invocation finished in 1h29m35.862302393s with status FAILED