[21:41:48.604] New invocation is queued and will start shortly
[21:41:49.684] Starting the invocation (attempt 1)
[21:41:49.710] Popped buildset tag "buildset:commit/gitiles/qemu.googlesource.com/qemu/+/ab8008b231e758e03c87c1c483c03afdd9c02e19"
[21:41:49.710] Popped gitiles commit info from properties and tags
[21:41:49.710] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:41:49.710] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:41:49.711] Buildbucket request:
{
"requestId": "8868693607571430608",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "qemu-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8868693607571430608",
"job": "fuchsia/ci-qemu-mac-x64-74eaa511",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "6657f3bb55edba8f068cbc9ac40bb230ea1d7a09"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@6657f3bb55edba8f068cbc9ac40bb230ea1d7a09",
"title": "6657f3bb55edba8f068cbc9ac40bb230ea1d7a09",
"url": "https://qemu.googlesource.com/qemu/+/6657f3bb55edba8f068cbc9ac40bb230ea1d7a09"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "c921e5496f23221335bea0c9104364409cd0b2b8"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@c921e5496f23221335bea0c9104364409cd0b2b8",
"title": "c921e5496f23221335bea0c9104364409cd0b2b8",
"url": "https://qemu.googlesource.com/qemu/+/c921e5496f23221335bea0c9104364409cd0b2b8"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "e5eacba4a3e6efdae8cda323f9a2ab5cfb1d0492"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@e5eacba4a3e6efdae8cda323f9a2ab5cfb1d0492",
"title": "e5eacba4a3e6efdae8cda323f9a2ab5cfb1d0492",
"url": "https://qemu.googlesource.com/qemu/+/e5eacba4a3e6efdae8cda323f9a2ab5cfb1d0492"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "7bc2cbe3306899559c4da1b05e410235d994e22d"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@7bc2cbe3306899559c4da1b05e410235d994e22d",
"title": "7bc2cbe3306899559c4da1b05e410235d994e22d",
"url": "https://qemu.googlesource.com/qemu/+/7bc2cbe3306899559c4da1b05e410235d994e22d"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "d343f395e371aa1b859eb42be331918931a6637e"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@d343f395e371aa1b859eb42be331918931a6637e",
"title": "d343f395e371aa1b859eb42be331918931a6637e",
"url": "https://qemu.googlesource.com/qemu/+/d343f395e371aa1b859eb42be331918931a6637e"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "6e9a6d57bf8de9e1edc207c24ed4e9e9508838c1"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@6e9a6d57bf8de9e1edc207c24ed4e9e9508838c1",
"title": "6e9a6d57bf8de9e1edc207c24ed4e9e9508838c1",
"url": "https://qemu.googlesource.com/qemu/+/6e9a6d57bf8de9e1edc207c24ed4e9e9508838c1"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "9adc8d052d1c6b3015ca07a6a0f0833fb725e06f"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@9adc8d052d1c6b3015ca07a6a0f0833fb725e06f",
"title": "9adc8d052d1c6b3015ca07a6a0f0833fb725e06f",
"url": "https://qemu.googlesource.com/qemu/+/9adc8d052d1c6b3015ca07a6a0f0833fb725e06f"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "4149afca711cc735a84ad61314bdcf41f69a7607"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@4149afca711cc735a84ad61314bdcf41f69a7607",
"title": "4149afca711cc735a84ad61314bdcf41f69a7607",
"url": "https://qemu.googlesource.com/qemu/+/4149afca711cc735a84ad61314bdcf41f69a7607"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "1ed8903916394fca2347c700da974ca3856274b2"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@1ed8903916394fca2347c700da974ca3856274b2",
"title": "1ed8903916394fca2347c700da974ca3856274b2",
"url": "https://qemu.googlesource.com/qemu/+/1ed8903916394fca2347c700da974ca3856274b2"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "fe6a74f365743ae3ce2cbbc4d44617d51a446a02"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@fe6a74f365743ae3ce2cbbc4d44617d51a446a02",
"title": "fe6a74f365743ae3ce2cbbc4d44617d51a446a02",
"url": "https://qemu.googlesource.com/qemu/+/fe6a74f365743ae3ce2cbbc4d44617d51a446a02"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "8cb17f9c36f3f92a4f653a0b369ec7ab82ccf5f0"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@8cb17f9c36f3f92a4f653a0b369ec7ab82ccf5f0",
"title": "8cb17f9c36f3f92a4f653a0b369ec7ab82ccf5f0",
"url": "https://qemu.googlesource.com/qemu/+/8cb17f9c36f3f92a4f653a0b369ec7ab82ccf5f0"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "09759245cf762d2fed4259494ec31198ac1ec0f4"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@09759245cf762d2fed4259494ec31198ac1ec0f4",
"title": "09759245cf762d2fed4259494ec31198ac1ec0f4",
"url": "https://qemu.googlesource.com/qemu/+/09759245cf762d2fed4259494ec31198ac1ec0f4"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "d14c8cc69dcd64031019da857f93ee5f6d64bb75"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@d14c8cc69dcd64031019da857f93ee5f6d64bb75",
"title": "d14c8cc69dcd64031019da857f93ee5f6d64bb75",
"url": "https://qemu.googlesource.com/qemu/+/d14c8cc69dcd64031019da857f93ee5f6d64bb75"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "5d1d32ce9d26a7cdc1c60dceb1b0c55ff9ad9b3e"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@5d1d32ce9d26a7cdc1c60dceb1b0c55ff9ad9b3e",
"title": "5d1d32ce9d26a7cdc1c60dceb1b0c55ff9ad9b3e",
"url": "https://qemu.googlesource.com/qemu/+/5d1d32ce9d26a7cdc1c60dceb1b0c55ff9ad9b3e"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "6f607941b1c01679d6d3dca036ddd23bbe95a44c"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@6f607941b1c01679d6d3dca036ddd23bbe95a44c",
"title": "6f607941b1c01679d6d3dca036ddd23bbe95a44c",
"url": "https://qemu.googlesource.com/qemu/+/6f607941b1c01679d6d3dca036ddd23bbe95a44c"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "69620c091d62f3a7016469c83b9064258524891d"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@69620c091d62f3a7016469c83b9064258524891d",
"title": "69620c091d62f3a7016469c83b9064258524891d",
"url": "https://qemu.googlesource.com/qemu/+/69620c091d62f3a7016469c83b9064258524891d"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "30b123acbb89bff8ce7e46f9eef8ad01c2f173c6"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@30b123acbb89bff8ce7e46f9eef8ad01c2f173c6",
"title": "30b123acbb89bff8ce7e46f9eef8ad01c2f173c6",
"url": "https://qemu.googlesource.com/qemu/+/30b123acbb89bff8ce7e46f9eef8ad01c2f173c6"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "34523df31962401871ff091a9d0993ac87a9848a"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@34523df31962401871ff091a9d0993ac87a9848a",
"title": "34523df31962401871ff091a9d0993ac87a9848a",
"url": "https://qemu.googlesource.com/qemu/+/34523df31962401871ff091a9d0993ac87a9848a"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "caf2e8de4ed056acad4fbdb6fe420d8124d38f11"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@caf2e8de4ed056acad4fbdb6fe420d8124d38f11",
"title": "caf2e8de4ed056acad4fbdb6fe420d8124d38f11",
"url": "https://qemu.googlesource.com/qemu/+/caf2e8de4ed056acad4fbdb6fe420d8124d38f11"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://qemu.googlesource.com/qemu",
"revision": "ab8008b231e758e03c87c1c483c03afdd9c02e19"
},
"id": "https://qemu.googlesource.com/qemu/+/refs/heads/master@ab8008b231e758e03c87c1c483c03afdd9c02e19",
"title": "ab8008b231e758e03c87c1c483c03afdd9c02e19",
"url": "https://qemu.googlesource.com/qemu/+/ab8008b231e758e03c87c1c483c03afdd9c02e19"
}
]
}
},
"gitilesCommit": {
"host": "qemu.googlesource.com",
"project": "qemu",
"id": "ab8008b231e758e03c87c1c483c03afdd9c02e19",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8868693607571430608"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/ci-qemu-mac-x64-74eaa511"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:41:50.146] Scheduled build:
{
"id": "8703263545683117009",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "qemu-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-09-19T21:41:49.775968828Z",
"updateTime": "2025-09-19T21:41:49.775968828Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "qemu.googlesource.com",
"project": "qemu",
"id": "ab8008b231e758e03c87c1c483c03afdd9c02e19",
"ref": "refs/heads/master"
}
},
"canary": true
}
[21:41:50.146] Task URL: https://cr-buildbucket.appspot.com/build/8703263545683117009
[21:41:50.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:2:0) after 8m39s
[21:42:09.944] Received PubSub notification, asking Buildbucket for the build status
[21:42:09.966] Build status: SCHEDULED
[21:50:29.255] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:2:0)
[21:50:29.286] Build status: SCHEDULED
[21:50:29.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:4:0) after 4m35s
[21:55:04.303] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:4:0)
[21:55:04.349] Build status: SCHEDULED
[21:55:04.349] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:5:0) after 1m2s
[21:56:06.427] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:5:0)
[21:56:06.460] Build status: SCHEDULED
[21:56:06.460] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:6:0) after 2m24s
[21:58:30.443] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:6:0)
[21:58:30.525] Build status: SCHEDULED
[21:58:30.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:7:0) after 4m40s
[22:03:10.647] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:7:0)
[22:03:10.674] Build status: SCHEDULED
[22:03:10.674] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:8:0) after 6m33s
[22:09:43.691] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:8:0)
[22:09:43.723] Build status: SCHEDULED
[22:09:43.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:9:0) after 6m35s
[22:16:18.816] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:9:0)
[22:16:18.856] Build status: SCHEDULED
[22:16:18.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:10:0) after 3m51s
[22:20:09.878] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:10:0)
[22:20:09.938] Build status: SCHEDULED
[22:20:09.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:11:0) after 3m25s
[22:23:34.979] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:11:0)
[22:23:34.999] Build status: SCHEDULED
[22:23:34.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:12:0) after 4m39s
[22:28:14.016] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:12:0)
[22:28:14.076] Build status: SCHEDULED
[22:28:14.076] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:13:0) after 4m36s
[22:32:50.093] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:13:0)
[22:32:50.115] Build status: SCHEDULED
[22:32:50.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:14:0) after 9m12s
[22:42:02.094] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:14:0)
[22:42:02.116] Build status: SCHEDULED
[22:42:02.116] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:15:0) after 1m24s
[22:43:26.133] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:15:0)
[22:43:26.149] Build status: SCHEDULED
[22:43:26.149] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:16:0) after 3m47s
[22:47:13.145] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:16:0)
[22:47:13.172] Build status: SCHEDULED
[22:47:13.172] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:17:0) after 1m32s
[22:48:45.147] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:17:0)
[22:48:45.164] Build status: SCHEDULED
[22:48:45.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:18:0) after 3m57s
[22:52:42.231] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:18:0)
[22:52:42.255] Build status: SCHEDULED
[22:52:42.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:19:0) after 3m31s
[22:56:13.280] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:19:0)
[22:56:13.316] Build status: SCHEDULED
[22:56:13.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:20:0) after 1m38s
[22:57:51.282] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:20:0)
[22:57:51.303] Build status: SCHEDULED
[22:57:51.303] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:21:0) after 4m33s
[23:02:24.321] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:21:0)
[23:02:24.541] Build status: SCHEDULED
[23:02:24.541] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:22:0) after 2m15s
[23:04:39.559] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:22:0)
[23:04:39.583] Build status: SCHEDULED
[23:04:39.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:23:0) after 4m39s
[23:09:18.648] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:23:0)
[23:09:18.666] Build status: SCHEDULED
[23:09:18.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:24:0) after 5m21s
[23:14:39.748] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:24:0)
[23:14:39.840] Build status: SCHEDULED
[23:14:39.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:25:0) after 9m7s
[23:23:08.114] Received PubSub notification, asking Buildbucket for the build status
[23:23:08.133] Build status: STARTED
[23:23:46.858] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:25:0)
[23:23:46.875] Build status: STARTED
[23:23:46.875] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:27:0) after 8m21s
[23:32:07.894] Handling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:27:0)
[23:32:07.934] Build status: STARTED
[23:32:07.934] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-qemu-mac-x64-74eaa511:8868693607571430608:28:0) after 8m31s
[23:35:55.625] Received PubSub notification, asking Buildbucket for the build status
[23:35:55.643] Build:
{
"id": "8703263545683117009",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "qemu-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-09-19T21:41:49.775968828Z",
"startTime": "2025-09-19T23:23:07.887078927Z",
"endTime": "2025-09-19T23:35:55.305009711Z",
"updateTime": "2025-09-19T23:35:55.305009711Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "qemu.googlesource.com",
"project": "qemu",
"id": "ab8008b231e758e03c87c1c483c03afdd9c02e19",
"ref": "refs/heads/master"
}
},
"canary": true
}
[23:35:55.643] Invocation finished in 1h54m7.052943072s with status SUCCEEDED