[17:49:28.286] New invocation is queued and will start shortly
[17:49:29.424] Starting the invocation (attempt 1)
[17:49:29.446] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3fe05643482433f8c491f01b5b13930cd1599ab0"
[17:49:29.446] Popped gitiles commit info from properties and tags
[17:49:29.446] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:49:29.446] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:49:29.447] Buildbucket request:
{
"requestId": "8853306740758303664",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8853306740758303664",
"job": "chromium-m145/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b0cdb8959819725e178575c7e593862a434840b3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@b0cdb8959819725e178575c7e593862a434840b3",
"title": "b0cdb8959819725e178575c7e593862a434840b3",
"url": "https://chromium.googlesource.com/chromium/src/+/b0cdb8959819725e178575c7e593862a434840b3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "204d8e9062a890f73065d991490de38675cbd4ab"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@204d8e9062a890f73065d991490de38675cbd4ab",
"title": "204d8e9062a890f73065d991490de38675cbd4ab",
"url": "https://chromium.googlesource.com/chromium/src/+/204d8e9062a890f73065d991490de38675cbd4ab"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c3383c8ee9acd9cf08764b2d50b469165575d28e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@c3383c8ee9acd9cf08764b2d50b469165575d28e",
"title": "c3383c8ee9acd9cf08764b2d50b469165575d28e",
"url": "https://chromium.googlesource.com/chromium/src/+/c3383c8ee9acd9cf08764b2d50b469165575d28e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "49a43fa643bcd8dfaaa2a5df6377062d8a453bab"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@49a43fa643bcd8dfaaa2a5df6377062d8a453bab",
"title": "49a43fa643bcd8dfaaa2a5df6377062d8a453bab",
"url": "https://chromium.googlesource.com/chromium/src/+/49a43fa643bcd8dfaaa2a5df6377062d8a453bab"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "745149e0f72db84e5f0b179e1805e3158d00c111"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@745149e0f72db84e5f0b179e1805e3158d00c111",
"title": "745149e0f72db84e5f0b179e1805e3158d00c111",
"url": "https://chromium.googlesource.com/chromium/src/+/745149e0f72db84e5f0b179e1805e3158d00c111"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "349ef56d67c401c59acc6382a74dfe7a47ecc6ac"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@349ef56d67c401c59acc6382a74dfe7a47ecc6ac",
"title": "349ef56d67c401c59acc6382a74dfe7a47ecc6ac",
"url": "https://chromium.googlesource.com/chromium/src/+/349ef56d67c401c59acc6382a74dfe7a47ecc6ac"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cbdaed2486b35c23144df877f4b1bed2a5ff45b5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@cbdaed2486b35c23144df877f4b1bed2a5ff45b5",
"title": "cbdaed2486b35c23144df877f4b1bed2a5ff45b5",
"url": "https://chromium.googlesource.com/chromium/src/+/cbdaed2486b35c23144df877f4b1bed2a5ff45b5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "842bd90d9acc17f3b541a68cd62e8747ebfaa4c8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@842bd90d9acc17f3b541a68cd62e8747ebfaa4c8",
"title": "842bd90d9acc17f3b541a68cd62e8747ebfaa4c8",
"url": "https://chromium.googlesource.com/chromium/src/+/842bd90d9acc17f3b541a68cd62e8747ebfaa4c8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c0800de216471190e0039b2a82cef1e8315e258e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@c0800de216471190e0039b2a82cef1e8315e258e",
"title": "c0800de216471190e0039b2a82cef1e8315e258e",
"url": "https://chromium.googlesource.com/chromium/src/+/c0800de216471190e0039b2a82cef1e8315e258e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ce9def7b98a8f29ce4cabda18d3ef3d53d556919"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@ce9def7b98a8f29ce4cabda18d3ef3d53d556919",
"title": "ce9def7b98a8f29ce4cabda18d3ef3d53d556919",
"url": "https://chromium.googlesource.com/chromium/src/+/ce9def7b98a8f29ce4cabda18d3ef3d53d556919"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fcf0cc5633eb354cb1d74f3b6864080b681a7585"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@fcf0cc5633eb354cb1d74f3b6864080b681a7585",
"title": "fcf0cc5633eb354cb1d74f3b6864080b681a7585",
"url": "https://chromium.googlesource.com/chromium/src/+/fcf0cc5633eb354cb1d74f3b6864080b681a7585"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3fe05643482433f8c491f01b5b13930cd1599ab0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@3fe05643482433f8c491f01b5b13930cd1599ab0",
"title": "3fe05643482433f8c491f01b5b13930cd1599ab0",
"url": "https://chromium.googlesource.com/chromium/src/+/3fe05643482433f8c491f01b5b13930cd1599ab0"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3fe05643482433f8c491f01b5b13930cd1599ab0",
"ref": "refs/branch-heads/7632"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8853306740758303664"
},
{
"key": "scheduler_job_id",
"value": "chromium-m145/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:49:29.930] Scheduled build:
{
"id": "8687876678839042097",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win32-official"
},
"number": 310,
"createdBy": "project:chromium-m145",
"createTime": "2026-03-08T17:49:29.494669460Z",
"updateTime": "2026-03-08T17:49:29.494669460Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3fe05643482433f8c491f01b5b13930cd1599ab0",
"ref": "refs/branch-heads/7632"
}
}
}
[17:49:29.930] Task URL: https://cr-buildbucket.appspot.com/build/8687876678839042097
[17:49:29.931] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:2:0) after 9m33s
[17:49:30.899] Received PubSub notification, asking Buildbucket for the build status
[17:49:30.939] Build status: SCHEDULED
[17:49:34.012] Received PubSub notification, asking Buildbucket for the build status
[17:49:34.033] Build status: STARTED
[17:59:02.948] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:2:0)
[17:59:02.964] Build status: STARTED
[17:59:02.964] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:5:0) after 1m1s
[18:00:03.994] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:5:0)
[18:00:04.015] Build status: STARTED
[18:00:04.015] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:6:0) after 7m42s
[18:07:46.073] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:6:0)
[18:07:46.091] Build status: STARTED
[18:07:46.091] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:7:0) after 8m56s
[18:16:42.107] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:7:0)
[18:16:42.152] Build status: STARTED
[18:16:42.152] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:8:0) after 3m12s
[18:19:54.189] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:8:0)
[18:19:54.209] Build status: STARTED
[18:19:54.209] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:9:0) after 1m56s
[18:21:50.227] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:9:0)
[18:21:50.278] Build status: STARTED
[18:21:50.278] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:10:0) after 1m32s
[18:23:22.338] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:10:0)
[18:23:22.357] Build status: STARTED
[18:23:22.357] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:11:0) after 4m54s
[18:28:16.602] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:11:0)
[18:28:16.620] Build status: STARTED
[18:28:16.620] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:12:0) after 4m24s
[18:32:40.634] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:12:0)
[18:32:40.724] Build status: STARTED
[18:32:40.724] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:13:0) after 7m16s
[18:39:56.927] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:13:0)
[18:39:56.946] Build status: STARTED
[18:39:56.946] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:14:0) after 6m41s
[18:46:37.990] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:14:0)
[18:46:38.007] Build status: STARTED
[18:46:38.007] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:15:0) after 8m36s
[18:55:14.028] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:15:0)
[18:55:14.047] Build status: STARTED
[18:55:14.047] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:16:0) after 3m16s
[18:58:30.119] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:16:0)
[18:58:30.136] Build status: STARTED
[18:58:30.136] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:17:0) after 5m4s
[19:03:34.657] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:17:0)
[19:03:34.676] Build status: STARTED
[19:03:34.676] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:18:0) after 4m18s
[19:07:52.855] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:18:0)
[19:07:52.925] Build status: STARTED
[19:07:52.925] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:19:0) after 4m12s
[19:12:05.376] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:19:0)
[19:12:05.393] Build status: STARTED
[19:12:05.393] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:20:0) after 6m46s
[19:18:51.411] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:20:0)
[19:18:51.456] Build status: STARTED
[19:18:51.456] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:21:0) after 1m38s
[19:20:29.575] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:21:0)
[19:20:29.595] Build status: STARTED
[19:20:29.595] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:22:0) after 1m52s
[19:22:21.664] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:22:0)
[19:22:21.711] Build status: STARTED
[19:22:21.711] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:23:0) after 4m51s
[19:27:12.794] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:23:0)
[19:27:12.865] Build status: STARTED
[19:27:12.865] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:24:0) after 8m48s
[19:36:01.285] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:24:0)
[19:36:01.352] Build status: STARTED
[19:36:01.352] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:25:0) after 8m41s
[19:44:42.455] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:25:0)
[19:44:42.476] Build status: STARTED
[19:44:42.476] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:26:0) after 4m6s
[19:48:48.568] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:26:0)
[19:48:48.590] Build status: STARTED
[19:48:48.590] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:27:0) after 8m41s
[19:57:29.853] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:27:0)
[19:57:29.871] Build status: STARTED
[19:57:29.871] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:28:0) after 6m57s
[20:04:26.938] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:28:0)
[20:04:26.954] Build status: STARTED
[20:04:26.954] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:29:0) after 4m4s
[20:08:30.975] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:29:0)
[20:08:30.995] Build status: STARTED
[20:08:30.995] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:30:0) after 9m23s
[20:17:54.020] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:30:0)
[20:17:54.046] Build status: STARTED
[20:17:54.046] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:31:0) after 3m5s
[20:20:59.136] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:31:0)
[20:20:59.185] Build status: STARTED
[20:20:59.185] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:32:0) after 3m16s
[20:24:15.295] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:32:0)
[20:24:15.360] Build status: STARTED
[20:24:15.360] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:33:0) after 2m12s
[20:26:27.378] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:33:0)
[20:26:27.599] Build status: STARTED
[20:26:27.599] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:34:0) after 8m12s
[20:34:39.695] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:34:0)
[20:34:39.712] Build status: STARTED
[20:34:39.712] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:35:0) after 4m43s
[20:39:22.858] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:35:0)
[20:39:22.875] Build status: STARTED
[20:39:22.875] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:36:0) after 8m3s
[20:47:26.025] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:36:0)
[20:47:26.048] Build status: STARTED
[20:47:26.048] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:37:0) after 3m57s
[20:51:23.187] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:37:0)
[20:51:23.202] Build status: STARTED
[20:51:23.202] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:38:0) after 7m37s
[20:59:00.271] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:38:0)
[20:59:00.299] Build status: STARTED
[20:59:00.299] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:39:0) after 9m28s
[21:08:28.415] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:39:0)
[21:08:28.463] Build status: STARTED
[21:08:28.463] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:40:0) after 8m44s
[21:17:12.488] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:40:0)
[21:17:12.506] Build status: STARTED
[21:17:12.506] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:41:0) after 9m37s
[21:26:49.578] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:41:0)
[21:26:49.600] Build status: STARTED
[21:26:49.600] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:42:0) after 7m13s
[21:34:02.661] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:42:0)
[21:34:02.678] Build status: STARTED
[21:34:02.678] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853306740758303664:43:0) after 4m24s
[21:37:26.582] Received PubSub notification, asking Buildbucket for the build status
[21:37:26.602] Build:
{
"id": "8687876678839042097",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win32-official"
},
"number": 310,
"createdBy": "project:chromium-m145",
"createTime": "2026-03-08T17:49:29.494669460Z",
"startTime": "2026-03-08T17:49:33.858788843Z",
"endTime": "2026-03-08T21:36:30.449562492Z",
"updateTime": "2026-03-08T21:36:30.449562492Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3fe05643482433f8c491f01b5b13930cd1599ab0",
"ref": "refs/branch-heads/7632"
}
}
}
[21:37:26.602] Invocation finished in 3h47m58.321869254s with status SUCCEEDED