[06:39:45.041] New invocation is queued and will start shortly
[06:39:46.433] Starting the invocation (attempt 1)
[06:39:46.458] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9e1f71e6ae6125b8f372262843824a73b0f7ae5e"
[06:39:46.458] Popped gitiles commit info from properties and tags
[06:39:46.458] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:39:46.459] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:39:46.459] Buildbucket request:
{
"requestId": "8840393509748967008",
"builder": {
"project": "chromium-m151",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8840393509748967008",
"job": "chromium-m151/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8dcc6ea5c2d5cf030eb475d3e99a931e98d648f8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@8dcc6ea5c2d5cf030eb475d3e99a931e98d648f8",
"title": "8dcc6ea5c2d5cf030eb475d3e99a931e98d648f8",
"url": "https://chromium.googlesource.com/chromium/src/+/8dcc6ea5c2d5cf030eb475d3e99a931e98d648f8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ff1cb5ea2d7700022f044c803fe112264ae22a2f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@ff1cb5ea2d7700022f044c803fe112264ae22a2f",
"title": "ff1cb5ea2d7700022f044c803fe112264ae22a2f",
"url": "https://chromium.googlesource.com/chromium/src/+/ff1cb5ea2d7700022f044c803fe112264ae22a2f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "16fbf0ceeef5013337ef7e0f6ff3bbf3092b5629"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@16fbf0ceeef5013337ef7e0f6ff3bbf3092b5629",
"title": "16fbf0ceeef5013337ef7e0f6ff3bbf3092b5629",
"url": "https://chromium.googlesource.com/chromium/src/+/16fbf0ceeef5013337ef7e0f6ff3bbf3092b5629"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2c7b7ffe24c82b9453ebe37ce45271286019637b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@2c7b7ffe24c82b9453ebe37ce45271286019637b",
"title": "2c7b7ffe24c82b9453ebe37ce45271286019637b",
"url": "https://chromium.googlesource.com/chromium/src/+/2c7b7ffe24c82b9453ebe37ce45271286019637b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5556087051325f8a8683294491b1fde64cfb7b3c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@5556087051325f8a8683294491b1fde64cfb7b3c",
"title": "5556087051325f8a8683294491b1fde64cfb7b3c",
"url": "https://chromium.googlesource.com/chromium/src/+/5556087051325f8a8683294491b1fde64cfb7b3c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cab6a6a4c79dfdf2861f13cbab0a55a21b843e7e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@cab6a6a4c79dfdf2861f13cbab0a55a21b843e7e",
"title": "cab6a6a4c79dfdf2861f13cbab0a55a21b843e7e",
"url": "https://chromium.googlesource.com/chromium/src/+/cab6a6a4c79dfdf2861f13cbab0a55a21b843e7e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9e1f71e6ae6125b8f372262843824a73b0f7ae5e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@9e1f71e6ae6125b8f372262843824a73b0f7ae5e",
"title": "9e1f71e6ae6125b8f372262843824a73b0f7ae5e",
"url": "https://chromium.googlesource.com/chromium/src/+/9e1f71e6ae6125b8f372262843824a73b0f7ae5e"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9e1f71e6ae6125b8f372262843824a73b0f7ae5e",
"ref": "refs/branch-heads/7922"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8840393509748967008"
},
{
"key": "scheduler_job_id",
"value": "chromium-m151/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:39:46.961] Scheduled build:
{
"id": "8674963447509290881",
"builder": {
"project": "chromium-m151",
"bucket": "ci",
"builder": "win32-official"
},
"number": 153,
"createdBy": "project:chromium-m151",
"createTime": "2026-07-29T06:39:46.555045358Z",
"updateTime": "2026-07-29T06:39:46.555045358Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9e1f71e6ae6125b8f372262843824a73b0f7ae5e",
"ref": "refs/branch-heads/7922"
}
}
}
[06:39:46.961] Task URL: https://cr-buildbucket.appspot.com/build/8674963447509290881
[06:39:46.961] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:2:0) after 5m56s
[06:39:48.005] Received PubSub notification, asking Buildbucket for the build status
[06:39:48.060] Build status: SCHEDULED
[06:41:46.866] Received PubSub notification, asking Buildbucket for the build status
[06:41:46.890] Build status: STARTED
[06:45:42.976] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:2:0)
[06:45:43.014] Build status: STARTED
[06:45:43.014] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:5:0) after 5m52s
[06:51:35.053] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:5:0)
[06:51:35.130] Build status: STARTED
[06:51:35.130] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:6:0) after 2m5s
[06:53:40.189] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:6:0)
[06:53:40.209] Build status: STARTED
[06:53:40.209] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:7:0) after 7m29s
[07:01:09.227] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:7:0)
[07:01:09.247] Build status: STARTED
[07:01:09.247] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:8:0) after 2m51s
[07:04:00.263] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:8:0)
[07:04:00.359] Build status: STARTED
[07:04:00.359] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:9:0) after 3m50s
[07:07:50.373] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:9:0)
[07:07:50.403] Build status: STARTED
[07:07:50.403] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:10:0) after 4m2s
[07:11:52.384] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:10:0)
[07:11:52.411] Build status: STARTED
[07:11:52.411] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:11:0) after 6m57s
[07:18:49.478] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:11:0)
[07:18:49.497] Build status: STARTED
[07:18:49.497] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:12:0) after 3m32s
[07:22:21.510] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:12:0)
[07:22:21.546] Build status: STARTED
[07:22:21.546] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:13:0) after 7m9s
[07:29:30.569] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:13:0)
[07:29:30.598] Build status: STARTED
[07:29:30.598] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:14:0) after 8m14s
[07:37:44.693] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:14:0)
[07:37:44.710] Build status: STARTED
[07:37:44.710] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:15:0) after 8m27s
[07:46:11.728] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:15:0)
[07:46:11.756] Build status: STARTED
[07:46:11.756] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:16:0) after 6m41s
[07:52:52.821] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:16:0)
[07:52:52.838] Build status: STARTED
[07:52:52.838] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:17:0) after 2m55s
[07:55:47.811] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:17:0)
[07:55:47.848] Build status: STARTED
[07:55:47.848] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:18:0) after 7m39s
[08:03:26.940] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:18:0)
[08:03:26.969] Build status: STARTED
[08:03:26.969] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:19:0) after 5m45s
[08:09:11.979] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:19:0)
[08:09:11.998] Build status: STARTED
[08:09:11.998] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:20:0) after 2m56s
[08:12:08.059] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:20:0)
[08:12:08.279] Build status: STARTED
[08:12:08.279] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:21:0) after 9m49s
[08:21:57.362] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:21:0)
[08:21:57.392] Build status: STARTED
[08:21:57.392] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:22:0) after 3m21s
[08:25:18.479] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:22:0)
[08:25:18.496] Build status: STARTED
[08:25:18.496] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:23:0) after 1m36s
[08:26:54.634] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:23:0)
[08:26:54.652] Build status: STARTED
[08:26:54.652] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:24:0) after 4m13s
[08:31:07.694] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:24:0)
[08:31:07.728] Build status: STARTED
[08:31:07.728] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:25:0) after 3m8s
[08:34:15.751] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:25:0)
[08:34:15.776] Build status: STARTED
[08:34:15.776] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:26:0) after 7m41s
[08:41:56.821] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:26:0)
[08:41:56.839] Build status: STARTED
[08:41:56.839] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:27:0) after 8m13s
[08:50:09.853] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:27:0)
[08:50:09.886] Build status: STARTED
[08:50:09.886] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:28:0) after 5m41s
[08:55:50.997] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:28:0)
[08:55:51.018] Build status: STARTED
[08:55:51.018] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:29:0) after 9m22s
[09:05:13.043] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:29:0)
[09:05:13.061] Build status: STARTED
[09:05:13.061] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:30:0) after 6m52s
[09:12:05.137] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:30:0)
[09:12:05.154] Build status: STARTED
[09:12:05.154] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:31:0) after 1m19s
[09:13:24.177] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:31:0)
[09:13:24.199] Build status: STARTED
[09:13:24.199] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:32:0) after 4m28s
[09:17:52.277] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:32:0)
[09:17:52.300] Build status: STARTED
[09:17:52.300] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:33:0) after 3m11s
[09:21:03.368] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:33:0)
[09:21:03.459] Build status: STARTED
[09:21:03.459] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:34:0) after 1m10s
[09:22:13.476] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:34:0)
[09:22:13.550] Build status: STARTED
[09:22:13.550] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:35:0) after 5m53s
[09:28:06.610] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:35:0)
[09:28:06.631] Build status: STARTED
[09:28:06.631] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:36:0) after 3m54s
[09:32:00.692] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:36:0)
[09:32:00.714] Build status: STARTED
[09:32:00.714] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:37:0) after 3m39s
[09:35:39.791] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:37:0)
[09:35:39.808] Build status: STARTED
[09:35:39.808] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:38:0) after 9m52s
[09:45:31.863] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:38:0)
[09:45:31.879] Build status: STARTED
[09:45:31.879] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:39:0) after 8m48s
[09:54:19.849] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:39:0)
[09:54:19.871] Build status: STARTED
[09:54:19.871] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:40:0) after 8m45s
[10:03:04.861] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:40:0)
[10:03:04.880] Build status: STARTED
[10:03:04.880] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:41:0) after 3m10s
[10:06:14.966] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:41:0)
[10:06:15.001] Build status: STARTED
[10:06:15.001] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:42:0) after 7m15s
[10:13:30.075] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:42:0)
[10:13:30.099] Build status: STARTED
[10:13:30.099] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:43:0) after 5m53s
[10:19:23.260] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:43:0)
[10:19:23.338] Build status: STARTED
[10:19:23.338] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:44:0) after 9m14s
[10:28:37.374] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:44:0)
[10:28:37.398] Build status: STARTED
[10:28:37.398] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:45:0) after 7m3s
[10:35:40.412] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:45:0)
[10:35:40.487] Build status: STARTED
[10:35:40.487] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:46:0) after 5m41s
[10:41:21.483] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:46:0)
[10:41:21.601] Build status: STARTED
[10:41:21.601] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:47:0) after 1m57s
[10:43:18.619] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:47:0)
[10:43:18.636] Build status: STARTED
[10:43:18.636] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:48:0) after 6m41s
[10:49:59.625] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:48:0)
[10:49:59.653] Build status: STARTED
[10:49:59.653] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:49:0) after 7m39s
[10:57:38.752] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:49:0)
[10:57:38.779] Build status: STARTED
[10:57:38.779] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8840393509748967008:50:0) after 4m47s
[11:01:30.581] Received PubSub notification, asking Buildbucket for the build status
[11:01:30.602] Build:
{
"id": "8674963447509290881",
"builder": {
"project": "chromium-m151",
"bucket": "ci",
"builder": "win32-official"
},
"number": 153,
"createdBy": "project:chromium-m151",
"createTime": "2026-07-29T06:39:46.555045358Z",
"startTime": "2026-07-29T06:41:46.707708193Z",
"endTime": "2026-07-29T11:01:14.871966167Z",
"updateTime": "2026-07-29T11:01:14.871966167Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9e1f71e6ae6125b8f372262843824a73b0f7ae5e",
"ref": "refs/branch-heads/7922"
}
}
}
[11:01:30.602] Invocation finished in 4h21m45.567460972s with status SUCCEEDED