[17:38:58.111] New invocation is queued and will start shortly
[17:38:59.180] Starting the invocation (attempt 1)
[17:38:59.214] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/d07f4d8f6cf886ff4fb53600f47d3b5762b29961"
[17:38:59.214] Popped gitiles commit info from properties and tags
[17:38:59.214] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:38:59.216] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:38:59.216] Buildbucket request:
{
"requestId": "8935931834907138032",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-vulkan_cts"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8935931834907138032",
"job": "fuchsia/core.x64-release-vulkan_cts-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f6d3a36a591ab321be9bd416d8a85a61c63c34d7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f6d3a36a591ab321be9bd416d8a85a61c63c34d7",
"title": "f6d3a36a591ab321be9bd416d8a85a61c63c34d7",
"url": "https://fuchsia.googlesource.com/integration/+/f6d3a36a591ab321be9bd416d8a85a61c63c34d7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "43ab3bedd03a9a3485ec68926ed49ca53e54590b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@43ab3bedd03a9a3485ec68926ed49ca53e54590b",
"title": "43ab3bedd03a9a3485ec68926ed49ca53e54590b",
"url": "https://fuchsia.googlesource.com/integration/+/43ab3bedd03a9a3485ec68926ed49ca53e54590b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "958fbf5372b15c8014d6840f51951f4d6bdf6dec"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@958fbf5372b15c8014d6840f51951f4d6bdf6dec",
"title": "958fbf5372b15c8014d6840f51951f4d6bdf6dec",
"url": "https://fuchsia.googlesource.com/integration/+/958fbf5372b15c8014d6840f51951f4d6bdf6dec"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d6f4c855d1084571ac24fd53aa3fc001eba3d6b2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d6f4c855d1084571ac24fd53aa3fc001eba3d6b2",
"title": "d6f4c855d1084571ac24fd53aa3fc001eba3d6b2",
"url": "https://fuchsia.googlesource.com/integration/+/d6f4c855d1084571ac24fd53aa3fc001eba3d6b2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e4d7154808ce5de178e0052ac7d48950ad3b092e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e4d7154808ce5de178e0052ac7d48950ad3b092e",
"title": "e4d7154808ce5de178e0052ac7d48950ad3b092e",
"url": "https://fuchsia.googlesource.com/integration/+/e4d7154808ce5de178e0052ac7d48950ad3b092e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4814152d383605eb1687570c6a74b85036a5fe39"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4814152d383605eb1687570c6a74b85036a5fe39",
"title": "4814152d383605eb1687570c6a74b85036a5fe39",
"url": "https://fuchsia.googlesource.com/integration/+/4814152d383605eb1687570c6a74b85036a5fe39"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d1875b64f4fa0758e57e1ff035b8c4691c82986f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d1875b64f4fa0758e57e1ff035b8c4691c82986f",
"title": "d1875b64f4fa0758e57e1ff035b8c4691c82986f",
"url": "https://fuchsia.googlesource.com/integration/+/d1875b64f4fa0758e57e1ff035b8c4691c82986f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "56de33dc1540e4970bc7a0e0a46d4358f28d4357"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@56de33dc1540e4970bc7a0e0a46d4358f28d4357",
"title": "56de33dc1540e4970bc7a0e0a46d4358f28d4357",
"url": "https://fuchsia.googlesource.com/integration/+/56de33dc1540e4970bc7a0e0a46d4358f28d4357"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d07f4d8f6cf886ff4fb53600f47d3b5762b29961"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d07f4d8f6cf886ff4fb53600f47d3b5762b29961",
"title": "d07f4d8f6cf886ff4fb53600f47d3b5762b29961",
"url": "https://fuchsia.googlesource.com/integration/+/d07f4d8f6cf886ff4fb53600f47d3b5762b29961"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "d07f4d8f6cf886ff4fb53600f47d3b5762b29961",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8935931834907138032"
},
{
"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"
}
}
[17:38:59.685] Scheduled build:
{
"id": "8770501773030039089",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-vulkan_cts"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-08T17:38:59.273529931Z",
"updateTime": "2023-09-08T17:38:59.273529931Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "d07f4d8f6cf886ff4fb53600f47d3b5762b29961",
"ref": "refs/heads/main"
}
},
"canary": true
}
[17:38:59.685] Task URL: https://cr-buildbucket.appspot.com/build/8770501773030039089
[17:38:59.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:2:0) after 2m4s
[17:39:00.631] Received PubSub notification, asking Buildbucket for the build status
[17:39:00.719] Build status: SCHEDULED
[17:39:02.360] Received PubSub notification, asking Buildbucket for the build status
[17:39:02.385] Build status: STARTED
[17:41:03.751] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:2:0)
[17:41:03.774] Build status: STARTED
[17:41:03.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:5:0) after 7m44s
[17:48:47.804] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:5:0)
[17:48:47.823] Build status: STARTED
[17:48:47.823] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:6:0) after 4m29s
[17:53:17.025] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:6:0)
[17:53:17.042] Build status: STARTED
[17:53:17.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:7:0) after 5m39s
[17:58:56.056] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:7:0)
[17:58:56.078] Build status: STARTED
[17:58:56.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:8:0) after 5m3s
[18:03:59.107] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:8:0)
[18:03:59.131] Build status: STARTED
[18:03:59.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:9:0) after 9m21s
[18:13:20.142] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:9:0)
[18:13:20.167] Build status: STARTED
[18:13:20.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:10:0) after 5m55s
[18:19:15.194] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:10:0)
[18:19:15.249] Build status: STARTED
[18:19:15.249] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:11:0) after 9m16s
[18:28:31.292] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:11:0)
[18:28:31.321] Build status: STARTED
[18:28:31.321] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:12:0) after 9m26s
[18:37:57.492] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:12:0)
[18:37:57.512] Build status: STARTED
[18:37:57.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:13:0) after 2m55s
[18:40:52.507] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:13:0)
[18:40:52.526] Build status: STARTED
[18:40:52.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:14:0) after 8m50s
[18:49:42.917] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:14:0)
[18:49:42.940] Build status: STARTED
[18:49:42.941] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935931834907138032:15:0) after 3m45s
[18:50:15.133] Received PubSub notification, asking Buildbucket for the build status
[18:50:15.160] Build:
{
"id": "8770501773030039089",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-vulkan_cts"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-08T17:38:59.273529931Z",
"startTime": "2023-09-08T17:39:01.405502Z",
"endTime": "2023-09-08T18:50:14.680246010Z",
"updateTime": "2023-09-08T18:50:14.680246010Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "d07f4d8f6cf886ff4fb53600f47d3b5762b29961",
"ref": "refs/heads/main"
}
},
"canary": true
}
[18:50:15.160] Invocation finished in 1h11m17.05966575s with status FAILED