[06:02:52.860] New invocation is queued and will start shortly
[06:02:53.889] Starting the invocation (attempt 1)
[06:02:53.915] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/a7fe50aff0599c5b7ba21edb6b2d46817fac1fb2"
[06:02:53.915] Popped gitiles commit info from properties and tags
[06:02:53.915] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:02:53.916] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:02:53.916] Buildbucket request:
{
"requestId": "8935885031976342736",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-vulkan_cts"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8935885031976342736",
"job": "fuchsia/core.x64-release-vulkan_cts-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "eb4dd8d346af2cdaf545f4dd99a33d75b2859f1d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@eb4dd8d346af2cdaf545f4dd99a33d75b2859f1d",
"title": "eb4dd8d346af2cdaf545f4dd99a33d75b2859f1d",
"url": "https://fuchsia.googlesource.com/integration/+/eb4dd8d346af2cdaf545f4dd99a33d75b2859f1d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b729e1b80ea2f84c3708c91a744a3c0c5d08f51b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b729e1b80ea2f84c3708c91a744a3c0c5d08f51b",
"title": "b729e1b80ea2f84c3708c91a744a3c0c5d08f51b",
"url": "https://fuchsia.googlesource.com/integration/+/b729e1b80ea2f84c3708c91a744a3c0c5d08f51b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "116e453076b1dadd7fbfb3c468a9092b3f7411af"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@116e453076b1dadd7fbfb3c468a9092b3f7411af",
"title": "116e453076b1dadd7fbfb3c468a9092b3f7411af",
"url": "https://fuchsia.googlesource.com/integration/+/116e453076b1dadd7fbfb3c468a9092b3f7411af"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "12d3f7f38bfa96db6a8bae44e493a7339a8e26ec"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@12d3f7f38bfa96db6a8bae44e493a7339a8e26ec",
"title": "12d3f7f38bfa96db6a8bae44e493a7339a8e26ec",
"url": "https://fuchsia.googlesource.com/integration/+/12d3f7f38bfa96db6a8bae44e493a7339a8e26ec"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b6597fbd8e688d0b492a40173f9cc3b9a67fa66a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b6597fbd8e688d0b492a40173f9cc3b9a67fa66a",
"title": "b6597fbd8e688d0b492a40173f9cc3b9a67fa66a",
"url": "https://fuchsia.googlesource.com/integration/+/b6597fbd8e688d0b492a40173f9cc3b9a67fa66a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "af2a39fe2914526ae81568f23881b1945e83c10c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@af2a39fe2914526ae81568f23881b1945e83c10c",
"title": "af2a39fe2914526ae81568f23881b1945e83c10c",
"url": "https://fuchsia.googlesource.com/integration/+/af2a39fe2914526ae81568f23881b1945e83c10c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a7fe50aff0599c5b7ba21edb6b2d46817fac1fb2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a7fe50aff0599c5b7ba21edb6b2d46817fac1fb2",
"title": "a7fe50aff0599c5b7ba21edb6b2d46817fac1fb2",
"url": "https://fuchsia.googlesource.com/integration/+/a7fe50aff0599c5b7ba21edb6b2d46817fac1fb2"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a7fe50aff0599c5b7ba21edb6b2d46817fac1fb2",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8935885031976342736"
},
{
"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"
}
}
[06:02:54.368] Scheduled build:
{
"id": "8770454970153079953",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-vulkan_cts"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-09T06:02:53.975887389Z",
"updateTime": "2023-09-09T06:02:53.975887389Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a7fe50aff0599c5b7ba21edb6b2d46817fac1fb2",
"ref": "refs/heads/main"
}
},
"canary": true
}
[06:02:54.368] Task URL: https://cr-buildbucket.appspot.com/build/8770454970153079953
[06:02:54.368] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:2:0) after 2m30s
[06:02:55.293] Received PubSub notification, asking Buildbucket for the build status
[06:02:55.329] Build status: SCHEDULED
[06:03:36.234] Received PubSub notification, asking Buildbucket for the build status
[06:03:36.265] Build status: STARTED
[06:05:24.644] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:2:0)
[06:05:24.661] Build status: STARTED
[06:05:24.661] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:5:0) after 1m16s
[06:06:40.691] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:5:0)
[06:06:40.717] Build status: STARTED
[06:06:40.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:6:0) after 6m16s
[06:12:56.751] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:6:0)
[06:12:56.775] Build status: STARTED
[06:12:56.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:7:0) after 2m2s
[06:14:58.812] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:7:0)
[06:14:58.829] Build status: STARTED
[06:14:58.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:8:0) after 8m6s
[06:23:04.872] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:8:0)
[06:23:04.930] Build status: STARTED
[06:23:04.930] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:9:0) after 1m41s
[06:24:46.152] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:9:0)
[06:24:46.210] Build status: STARTED
[06:24:46.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:10:0) after 4m25s
[06:29:11.277] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:10:0)
[06:29:11.292] Build status: STARTED
[06:29:11.292] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:11:0) after 9m19s
[06:38:30.328] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:11:0)
[06:38:30.349] Build status: STARTED
[06:38:30.349] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:12:0) after 7m18s
[06:45:48.499] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:12:0)
[06:45:48.517] Build status: STARTED
[06:45:48.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:13:0) after 7m27s
[06:53:15.555] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:13:0)
[06:53:15.574] Build status: STARTED
[06:53:15.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:14:0) after 7m41s
[07:00:56.543] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:14:0)
[07:00:56.568] Build status: STARTED
[07:00:56.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:15:0) after 1m8s
[07:02:04.553] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:15:0)
[07:02:04.609] Build status: STARTED
[07:02:04.609] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:16:0) after 1m43s
[07:03:47.631] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:16:0)
[07:03:47.660] Build status: STARTED
[07:03:47.660] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:17:0) after 3m2s
[07:06:49.688] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:17:0)
[07:06:49.706] Build status: STARTED
[07:06:49.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:18:0) after 1m1s
[07:07:50.733] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:18:0)
[07:07:50.760] Build status: STARTED
[07:07:50.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-vulkan_cts-e5a0ec7f:8935885031976342736:19:0) after 6m49s
[07:13:03.572] Received PubSub notification, asking Buildbucket for the build status
[07:13:03.598] Build:
{
"id": "8770454970153079953",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-vulkan_cts"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-09T06:02:53.975887389Z",
"startTime": "2023-09-09T06:03:33.597158Z",
"endTime": "2023-09-09T07:13:02.912026925Z",
"updateTime": "2023-09-09T07:13:02.912026925Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a7fe50aff0599c5b7ba21edb6b2d46817fac1fb2",
"ref": "refs/heads/main"
}
},
"canary": true
}
[07:13:03.598] Invocation finished in 1h10m10.745317657s with status FAILED