[08:25:46.595] New invocation is queued and will start shortly
[08:25:47.712] Starting the invocation (attempt 1)
[08:25:47.735] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/46836fdc37623162a0379ff0245a8b7c3a5ba261"
[08:25:47.735] Popped gitiles commit info from properties and tags
[08:25:47.736] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:25:47.736] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:25:47.736] Buildbucket request:
{
"requestId": "8847181611655515696",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8847181611655515696",
"job": "chromium-m148/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bc35878915f5e128d4c67754bd49a88abdb6ce98"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@bc35878915f5e128d4c67754bd49a88abdb6ce98",
"title": "bc35878915f5e128d4c67754bd49a88abdb6ce98",
"url": "https://chromium.googlesource.com/chromium/src/+/bc35878915f5e128d4c67754bd49a88abdb6ce98"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "023a891847b4e3d354f42c869b59eb6f2bcc9c9a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@023a891847b4e3d354f42c869b59eb6f2bcc9c9a",
"title": "023a891847b4e3d354f42c869b59eb6f2bcc9c9a",
"url": "https://chromium.googlesource.com/chromium/src/+/023a891847b4e3d354f42c869b59eb6f2bcc9c9a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "dfa47ef65bf52f83cc7a359a474687f39bfdfc35"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@dfa47ef65bf52f83cc7a359a474687f39bfdfc35",
"title": "dfa47ef65bf52f83cc7a359a474687f39bfdfc35",
"url": "https://chromium.googlesource.com/chromium/src/+/dfa47ef65bf52f83cc7a359a474687f39bfdfc35"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7323d7536b74ca86aac59423de3e0dc9f260f093"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@7323d7536b74ca86aac59423de3e0dc9f260f093",
"title": "7323d7536b74ca86aac59423de3e0dc9f260f093",
"url": "https://chromium.googlesource.com/chromium/src/+/7323d7536b74ca86aac59423de3e0dc9f260f093"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a015e7ea8f62adc6c647343395ce5cb9456e32a0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@a015e7ea8f62adc6c647343395ce5cb9456e32a0",
"title": "a015e7ea8f62adc6c647343395ce5cb9456e32a0",
"url": "https://chromium.googlesource.com/chromium/src/+/a015e7ea8f62adc6c647343395ce5cb9456e32a0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8bf86265f4bec4d07bdc5d78a0ff7f81b5826d3a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@8bf86265f4bec4d07bdc5d78a0ff7f81b5826d3a",
"title": "8bf86265f4bec4d07bdc5d78a0ff7f81b5826d3a",
"url": "https://chromium.googlesource.com/chromium/src/+/8bf86265f4bec4d07bdc5d78a0ff7f81b5826d3a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "46836fdc37623162a0379ff0245a8b7c3a5ba261"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@46836fdc37623162a0379ff0245a8b7c3a5ba261",
"title": "46836fdc37623162a0379ff0245a8b7c3a5ba261",
"url": "https://chromium.googlesource.com/chromium/src/+/46836fdc37623162a0379ff0245a8b7c3a5ba261"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "46836fdc37623162a0379ff0245a8b7c3a5ba261",
"ref": "refs/branch-heads/7778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8847181611655515696"
},
{
"key": "scheduler_job_id",
"value": "chromium-m148/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[08:25:48.220] Scheduled build:
{
"id": "8681751549740137361",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "mac-official"
},
"number": 223,
"createdBy": "project:chromium-m148",
"createTime": "2026-05-15T08:25:47.800419175Z",
"updateTime": "2026-05-15T08:25:47.800419175Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "46836fdc37623162a0379ff0245a8b7c3a5ba261",
"ref": "refs/branch-heads/7778"
}
}
}
[08:25:48.220] Task URL: https://cr-buildbucket.appspot.com/build/8681751549740137361
[08:25:48.221] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:2:0) after 1m6s
[08:25:49.163] Received PubSub notification, asking Buildbucket for the build status
[08:25:49.185] Build status: SCHEDULED
[08:25:53.303] Received PubSub notification, asking Buildbucket for the build status
[08:25:53.323] Build status: STARTED
[08:26:54.240] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:2:0)
[08:26:54.256] Build status: STARTED
[08:26:54.256] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:5:0) after 2m19s
[08:29:13.273] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:5:0)
[08:29:13.290] Build status: STARTED
[08:29:13.291] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:6:0) after 5m21s
[08:34:34.303] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:6:0)
[08:34:34.354] Build status: STARTED
[08:34:34.354] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:7:0) after 9m5s
[08:43:39.371] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:7:0)
[08:43:39.398] Build status: STARTED
[08:43:39.399] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:8:0) after 2m55s
[08:46:34.416] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:8:0)
[08:46:34.497] Build status: STARTED
[08:46:34.497] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:9:0) after 4m4s
[08:50:38.517] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:9:0)
[08:50:38.579] Build status: STARTED
[08:50:38.579] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:10:0) after 1m14s
[08:51:52.594] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:10:0)
[08:51:52.617] Build status: STARTED
[08:51:52.617] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:11:0) after 4m40s
[08:56:32.632] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:11:0)
[08:56:32.652] Build status: STARTED
[08:56:32.652] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:12:0) after 3m25s
[08:59:57.671] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:12:0)
[08:59:57.694] Build status: STARTED
[08:59:57.694] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:13:0) after 4m30s
[09:04:27.709] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:13:0)
[09:04:27.727] Build status: STARTED
[09:04:27.727] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:14:0) after 4m14s
[09:08:41.743] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:14:0)
[09:08:41.763] Build status: STARTED
[09:08:41.763] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:15:0) after 6m17s
[09:14:58.781] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:15:0)
[09:14:58.846] Build status: STARTED
[09:14:58.846] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:16:0) after 4m32s
[09:19:30.861] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:16:0)
[09:19:30.883] Build status: STARTED
[09:19:30.883] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:17:0) after 8m13s
[09:27:44.004] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:17:0)
[09:27:44.034] Build status: STARTED
[09:27:44.034] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:18:0) after 6m22s
[09:34:06.047] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:18:0)
[09:34:06.078] Build status: STARTED
[09:34:06.078] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:19:0) after 8m49s
[09:42:55.120] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:19:0)
[09:42:55.137] Build status: STARTED
[09:42:55.137] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:20:0) after 7m20s
[09:50:15.231] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:20:0)
[09:50:15.253] Build status: STARTED
[09:50:15.253] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:21:0) after 4m26s
[09:54:41.324] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:21:0)
[09:54:41.364] Build status: STARTED
[09:54:41.364] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:22:0) after 6m50s
[10:01:31.442] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:22:0)
[10:01:31.456] Build status: STARTED
[10:01:31.456] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:23:0) after 5m13s
[10:06:44.450] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:23:0)
[10:06:44.504] Build status: STARTED
[10:06:44.504] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:24:0) after 9m24s
[10:16:08.576] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:24:0)
[10:16:08.634] Build status: STARTED
[10:16:08.634] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:25:0) after 2m23s
[10:18:31.648] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:25:0)
[10:18:31.671] Build status: STARTED
[10:18:31.671] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:26:0) after 8m17s
[10:26:48.689] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:26:0)
[10:26:48.710] Build status: STARTED
[10:26:48.710] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:27:0) after 7m21s
[10:34:09.725] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:27:0)
[10:34:09.749] Build status: STARTED
[10:34:09.749] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:28:0) after 7m7s
[10:41:16.902] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:28:0)
[10:41:16.922] Build status: STARTED
[10:41:16.922] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:29:0) after 8m40s
[10:49:57.029] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:29:0)
[10:49:57.090] Build status: STARTED
[10:49:57.091] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:30:0) after 4m24s
[10:54:21.617] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:30:0)
[10:54:21.702] Build status: STARTED
[10:54:21.702] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:31:0) after 7m21s
[11:01:42.723] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:31:0)
[11:01:42.749] Build status: STARTED
[11:01:42.749] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:32:0) after 2m58s
[11:04:40.783] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:32:0)
[11:04:40.805] Build status: STARTED
[11:04:40.805] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:33:0) after 8m28s
[11:13:08.823] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:33:0)
[11:13:08.857] Build status: STARTED
[11:13:08.857] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:34:0) after 7m21s
[11:20:30.070] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:34:0)
[11:20:30.092] Build status: STARTED
[11:20:30.092] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:35:0) after 8m6s
[11:28:36.124] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:35:0)
[11:28:36.146] Build status: STARTED
[11:28:36.146] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:36:0) after 9m36s
[11:38:12.171] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:36:0)
[11:38:12.205] Build status: STARTED
[11:38:12.205] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:37:0) after 7m46s
[11:45:58.247] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:37:0)
[11:45:58.356] Build status: STARTED
[11:45:58.356] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:38:0) after 4m17s
[11:50:15.609] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:38:0)
[11:50:15.631] Build status: STARTED
[11:50:15.631] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:39:0) after 6m29s
[11:56:44.644] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:39:0)
[11:56:44.693] Build status: STARTED
[11:56:44.693] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:40:0) after 6m7s
[12:02:51.708] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:40:0)
[12:02:51.735] Build status: STARTED
[12:02:51.735] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:41:0) after 5m33s
[12:08:24.799] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:41:0)
[12:08:24.877] Build status: STARTED
[12:08:24.878] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:42:0) after 9m39s
[12:18:03.950] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:42:0)
[12:18:03.972] Build status: STARTED
[12:18:03.972] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:43:0) after 4m45s
[12:22:48.986] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:43:0)
[12:22:49.032] Build status: STARTED
[12:22:49.032] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:44:0) after 2m18s
[12:25:07.046] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:44:0)
[12:25:07.069] Build status: STARTED
[12:25:07.069] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:45:0) after 6m9s
[12:31:16.089] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:45:0)
[12:31:16.162] Build status: STARTED
[12:31:16.162] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:46:0) after 3m49s
[12:35:05.183] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:46:0)
[12:35:05.202] Build status: STARTED
[12:35:05.202] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8847181611655515696:47:0) after 7m38s
[12:35:11.988] Received PubSub notification, asking Buildbucket for the build status
[12:35:12.012] Build:
{
"id": "8681751549740137361",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "mac-official"
},
"number": 223,
"createdBy": "project:chromium-m148",
"createTime": "2026-05-15T08:25:47.800419175Z",
"startTime": "2026-05-15T08:25:53.053817569Z",
"endTime": "2026-05-15T12:35:11.644250579Z",
"updateTime": "2026-05-15T12:35:11.644250579Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "46836fdc37623162a0379ff0245a8b7c3a5ba261",
"ref": "refs/branch-heads/7778"
}
}
}
[12:35:12.012] Invocation finished in 4h9m25.421989717s with status SUCCEEDED