[08:04:07.061] New invocation is queued and will start shortly
[08:04:08.214] Starting the invocation (attempt 1)
[08:04:08.243] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9edc1956b77da767bd53d7fbe455fda81bff6d10"
[08:04:08.243] Popped gitiles commit info from properties and tags
[08:04:08.243] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:04:08.243] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:04:08.243] Buildbucket request:
{
"requestId": "8871915946148101072",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8871915946148101072",
"job": "chromium-m140/fuchsia-x64-cast-receiver-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a9d51a2e572de9848436c853b6ae34b8e8fcf983"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@a9d51a2e572de9848436c853b6ae34b8e8fcf983",
"title": "a9d51a2e572de9848436c853b6ae34b8e8fcf983",
"url": "https://chromium.googlesource.com/chromium/src/+/a9d51a2e572de9848436c853b6ae34b8e8fcf983"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ce59792dab0001b8decfba10c588e9cf5c78611f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@ce59792dab0001b8decfba10c588e9cf5c78611f",
"title": "ce59792dab0001b8decfba10c588e9cf5c78611f",
"url": "https://chromium.googlesource.com/chromium/src/+/ce59792dab0001b8decfba10c588e9cf5c78611f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "55f973d7ed044e0c2c5bbc8630797968d1c6f05c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@55f973d7ed044e0c2c5bbc8630797968d1c6f05c",
"title": "55f973d7ed044e0c2c5bbc8630797968d1c6f05c",
"url": "https://chromium.googlesource.com/chromium/src/+/55f973d7ed044e0c2c5bbc8630797968d1c6f05c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e98908b56985c23bfe9288c97fe60e7ff6ff3908"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@e98908b56985c23bfe9288c97fe60e7ff6ff3908",
"title": "e98908b56985c23bfe9288c97fe60e7ff6ff3908",
"url": "https://chromium.googlesource.com/chromium/src/+/e98908b56985c23bfe9288c97fe60e7ff6ff3908"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bf2a1f37dc3073c543af9392769609da9b9b3b97"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@bf2a1f37dc3073c543af9392769609da9b9b3b97",
"title": "bf2a1f37dc3073c543af9392769609da9b9b3b97",
"url": "https://chromium.googlesource.com/chromium/src/+/bf2a1f37dc3073c543af9392769609da9b9b3b97"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8e6b7d284905f66b5b5847823ac9ff939f25d64c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@8e6b7d284905f66b5b5847823ac9ff939f25d64c",
"title": "8e6b7d284905f66b5b5847823ac9ff939f25d64c",
"url": "https://chromium.googlesource.com/chromium/src/+/8e6b7d284905f66b5b5847823ac9ff939f25d64c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4aa0a9c4b08af01d597f08b29cbf7ac2ad4dfaf9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@4aa0a9c4b08af01d597f08b29cbf7ac2ad4dfaf9",
"title": "4aa0a9c4b08af01d597f08b29cbf7ac2ad4dfaf9",
"url": "https://chromium.googlesource.com/chromium/src/+/4aa0a9c4b08af01d597f08b29cbf7ac2ad4dfaf9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9edc1956b77da767bd53d7fbe455fda81bff6d10"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@9edc1956b77da767bd53d7fbe455fda81bff6d10",
"title": "9edc1956b77da767bd53d7fbe455fda81bff6d10",
"url": "https://chromium.googlesource.com/chromium/src/+/9edc1956b77da767bd53d7fbe455fda81bff6d10"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9edc1956b77da767bd53d7fbe455fda81bff6d10",
"ref": "refs/branch-heads/7339"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8871915946148101072"
},
{
"key": "scheduler_job_id",
"value": "chromium-m140/fuchsia-x64-cast-receiver-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[08:04:08.768] Scheduled build:
{
"id": "8706485884176496641",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 93,
"createdBy": "project:chromium-m140",
"createTime": "2025-08-15T08:04:08.315542486Z",
"updateTime": "2025-08-15T08:04:08.315542486Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9edc1956b77da767bd53d7fbe455fda81bff6d10",
"ref": "refs/branch-heads/7339"
}
}
}
[08:04:08.768] Task URL: https://cr-buildbucket.appspot.com/build/8706485884176496641
[08:04:08.768] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:2:0) after 4m10s
[08:04:08.883] Received PubSub notification, asking Buildbucket for the build status
[08:04:08.910] Build status: SCHEDULED
[08:08:18.771] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:2:0)
[08:08:18.862] Build status: SCHEDULED
[08:08:18.862] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:4:0) after 7m45s
[08:16:03.946] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:4:0)
[08:16:03.965] Build status: SCHEDULED
[08:16:03.965] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:5:0) after 2m23s
[08:18:27.030] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:5:0)
[08:18:27.051] Build status: SCHEDULED
[08:18:27.051] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:6:0) after 1m1s
[08:19:28.071] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:6:0)
[08:19:28.095] Build status: SCHEDULED
[08:19:28.096] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:7:0) after 2m24s
[08:21:52.114] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:7:0)
[08:21:52.138] Build status: SCHEDULED
[08:21:52.138] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:8:0) after 2m40s
[08:24:32.161] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:8:0)
[08:24:32.178] Build status: SCHEDULED
[08:24:32.178] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:9:0) after 5m35s
[08:30:07.196] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:9:0)
[08:30:07.222] Build status: SCHEDULED
[08:30:07.223] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:10:0) after 5m3s
[08:35:10.280] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:10:0)
[08:35:10.314] Build status: SCHEDULED
[08:35:10.314] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:11:0) after 8m25s
[08:35:51.763] Received PubSub notification, asking Buildbucket for the build status
[08:35:51.792] Build status: STARTED
[08:43:35.344] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:11:0)
[08:43:35.510] Build status: STARTED
[08:43:35.510] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:13:0) after 3m47s
[08:47:22.537] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:13:0)
[08:47:22.562] Build status: STARTED
[08:47:22.563] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:14:0) after 6m27s
[08:53:49.579] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:14:0)
[08:53:49.611] Build status: STARTED
[08:53:49.611] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:15:0) after 8m7s
[09:01:56.730] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:15:0)
[09:01:56.748] Build status: STARTED
[09:01:56.748] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:16:0) after 1m15s
[09:03:11.818] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:16:0)
[09:03:11.840] Build status: STARTED
[09:03:11.840] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:17:0) after 6m6s
[09:09:17.859] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:17:0)
[09:09:17.890] Build status: STARTED
[09:09:17.890] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:18:0) after 4m53s
[09:14:10.907] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:18:0)
[09:14:10.939] Build status: STARTED
[09:14:10.939] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:19:0) after 4m48s
[09:18:58.966] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:19:0)
[09:18:59.129] Build status: STARTED
[09:18:59.130] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:20:0) after 4m4s
[09:23:03.145] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:20:0)
[09:23:03.162] Build status: STARTED
[09:23:03.162] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:21:0) after 7m20s
[09:30:23.188] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:21:0)
[09:30:23.208] Build status: STARTED
[09:30:23.208] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:22:0) after 1m52s
[09:32:15.226] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:22:0)
[09:32:15.243] Build status: STARTED
[09:32:15.243] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:23:0) after 4m9s
[09:36:24.226] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:23:0)
[09:36:24.243] Build status: STARTED
[09:36:24.243] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:24:0) after 8m59s
[09:45:23.550] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:24:0)
[09:45:23.572] Build status: STARTED
[09:45:23.572] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:25:0) after 7m37s
[09:53:00.828] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:25:0)
[09:53:00.846] Build status: STARTED
[09:53:00.846] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:26:0) after 4m37s
[09:57:38.125] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:26:0)
[09:57:38.143] Build status: STARTED
[09:57:38.143] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:27:0) after 8m18s
[10:05:56.279] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:27:0)
[10:05:56.334] Build status: STARTED
[10:05:56.334] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:28:0) after 1m47s
[10:07:43.736] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:28:0)
[10:07:43.760] Build status: STARTED
[10:07:43.760] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:29:0) after 8m23s
[10:16:06.929] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:29:0)
[10:16:06.997] Build status: STARTED
[10:16:06.997] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:30:0) after 9m18s
[10:25:25.389] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:30:0)
[10:25:25.478] Build status: STARTED
[10:25:25.478] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:31:0) after 3m50s
[10:29:15.511] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:31:0)
[10:29:15.538] Build status: STARTED
[10:29:15.538] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:32:0) after 1m10s
[10:30:25.948] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:32:0)
[10:30:25.966] Build status: STARTED
[10:30:25.966] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:33:0) after 2m1s
[10:32:27.326] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:33:0)
[10:32:27.348] Build status: STARTED
[10:32:27.348] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:34:0) after 4m36s
[10:37:03.351] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:34:0)
[10:37:03.376] Build status: STARTED
[10:37:03.376] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:35:0) after 7m9s
[10:44:13.213] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:35:0)
[10:44:13.236] Build status: STARTED
[10:44:13.237] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:36:0) after 4m4s
[10:48:17.247] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:36:0)
[10:48:17.267] Build status: STARTED
[10:48:17.267] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:37:0) after 9m4s
[10:57:21.243] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:37:0)
[10:57:21.265] Build status: STARTED
[10:57:21.265] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:38:0) after 2m27s
[10:59:48.319] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:38:0)
[10:59:48.356] Build status: STARTED
[10:59:48.356] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:39:0) after 4m10s
[11:03:58.450] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:39:0)
[11:03:58.467] Build status: STARTED
[11:03:58.467] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:40:0) after 5m13s
[11:09:11.488] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:40:0)
[11:09:11.508] Build status: STARTED
[11:09:11.508] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:41:0) after 2m28s
[11:11:39.529] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:41:0)
[11:11:39.563] Build status: STARTED
[11:11:39.563] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:42:0) after 7m51s
[11:19:30.839] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:42:0)
[11:19:30.887] Build status: STARTED
[11:19:30.887] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8871915946148101072:43:0) after 5m33s
[11:20:49.016] Received PubSub notification, asking Buildbucket for the build status
[11:20:49.039] Build:
{
"id": "8706485884176496641",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 93,
"createdBy": "project:chromium-m140",
"createTime": "2025-08-15T08:04:08.315542486Z",
"startTime": "2025-08-15T08:35:51.558595822Z",
"endTime": "2025-08-15T11:20:48.558973554Z",
"updateTime": "2025-08-15T11:20:48.558973554Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9edc1956b77da767bd53d7fbe455fda81bff6d10",
"ref": "refs/branch-heads/7339"
}
}
}
[11:20:49.039] Invocation finished in 3h16m41.987822751s with status SUCCEEDED