[05:04:20.426] New invocation is queued and will start shortly
[05:04:22.669] Starting the invocation (attempt 1)
[05:04:22.698] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/66241bfafcee04775298657d97015ea5e738f237"
[05:04:22.698] Popped gitiles commit info from properties and tags
[05:04:22.698] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:04:22.699] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:04:22.700] Buildbucket request:
{
"requestId": "8838406379180279664",
"builder": {
"project": "chromium-m151",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8838406379180279664",
"job": "chromium-m151/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8e6cd11916768ba42407dc2fc73821e4762e82d7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@8e6cd11916768ba42407dc2fc73821e4762e82d7",
"title": "8e6cd11916768ba42407dc2fc73821e4762e82d7",
"url": "https://chromium.googlesource.com/chromium/src/+/8e6cd11916768ba42407dc2fc73821e4762e82d7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3c062c5b1cc205fec397c493afc35cf16307f26b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@3c062c5b1cc205fec397c493afc35cf16307f26b",
"title": "3c062c5b1cc205fec397c493afc35cf16307f26b",
"url": "https://chromium.googlesource.com/chromium/src/+/3c062c5b1cc205fec397c493afc35cf16307f26b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2deadf7b012fb993626489145293c0987e1b5933"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@2deadf7b012fb993626489145293c0987e1b5933",
"title": "2deadf7b012fb993626489145293c0987e1b5933",
"url": "https://chromium.googlesource.com/chromium/src/+/2deadf7b012fb993626489145293c0987e1b5933"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "714fcb7b2d9c7f91976a69adf2304653495d3247"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@714fcb7b2d9c7f91976a69adf2304653495d3247",
"title": "714fcb7b2d9c7f91976a69adf2304653495d3247",
"url": "https://chromium.googlesource.com/chromium/src/+/714fcb7b2d9c7f91976a69adf2304653495d3247"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1216d5f2bdec54f87113738e3d31d2e1f9fff85f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@1216d5f2bdec54f87113738e3d31d2e1f9fff85f",
"title": "1216d5f2bdec54f87113738e3d31d2e1f9fff85f",
"url": "https://chromium.googlesource.com/chromium/src/+/1216d5f2bdec54f87113738e3d31d2e1f9fff85f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "87671b82fe6458823399fa315c54e19ac1cb75dd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@87671b82fe6458823399fa315c54e19ac1cb75dd",
"title": "87671b82fe6458823399fa315c54e19ac1cb75dd",
"url": "https://chromium.googlesource.com/chromium/src/+/87671b82fe6458823399fa315c54e19ac1cb75dd"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "64306f41d8f6793f93a3d0d34843c4afed5dc7a9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@64306f41d8f6793f93a3d0d34843c4afed5dc7a9",
"title": "64306f41d8f6793f93a3d0d34843c4afed5dc7a9",
"url": "https://chromium.googlesource.com/chromium/src/+/64306f41d8f6793f93a3d0d34843c4afed5dc7a9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "66241bfafcee04775298657d97015ea5e738f237"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@66241bfafcee04775298657d97015ea5e738f237",
"title": "66241bfafcee04775298657d97015ea5e738f237",
"url": "https://chromium.googlesource.com/chromium/src/+/66241bfafcee04775298657d97015ea5e738f237"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "66241bfafcee04775298657d97015ea5e738f237",
"ref": "refs/branch-heads/7922"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8838406379180279664"
},
{
"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"
}
}
[05:04:23.327] Scheduled build:
{
"id": "8672976316071025041",
"builder": {
"project": "chromium-m151",
"bucket": "ci",
"builder": "win32-official"
},
"number": 275,
"createdBy": "project:chromium-m151",
"createTime": "2026-08-20T05:04:22.771241573Z",
"updateTime": "2026-08-20T05:04:22.771241573Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "66241bfafcee04775298657d97015ea5e738f237",
"ref": "refs/branch-heads/7922"
}
}
}
[05:04:23.327] Task URL: https://cr-buildbucket.appspot.com/build/8672976316071025041
[05:04:23.327] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:2:0) after 5m1s
[05:04:24.129] Received PubSub notification, asking Buildbucket for the build status
[05:04:24.196] Build status: SCHEDULED
[05:04:27.655] Received PubSub notification, asking Buildbucket for the build status
[05:04:27.682] Build status: STARTED
[05:09:24.302] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:2:0)
[05:09:24.328] Build status: STARTED
[05:09:24.328] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:5:0) after 1m2s
[05:10:26.390] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:5:0)
[05:10:26.412] Build status: STARTED
[05:10:26.412] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:6:0) after 6m17s
[05:16:43.421] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:6:0)
[05:16:43.451] Build status: STARTED
[05:16:43.451] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:7:0) after 6m15s
[05:22:58.482] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:7:0)
[05:22:58.554] Build status: STARTED
[05:22:58.554] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:8:0) after 2m40s
[05:25:38.580] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:8:0)
[05:25:38.599] Build status: STARTED
[05:25:38.599] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:9:0) after 3m26s
[05:29:04.756] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:9:0)
[05:29:04.833] Build status: STARTED
[05:29:04.833] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:10:0) after 8m43s
[05:37:47.973] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:10:0)
[05:37:47.991] Build status: STARTED
[05:37:47.991] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:11:0) after 5m56s
[05:43:44.041] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:11:0)
[05:43:44.061] Build status: STARTED
[05:43:44.061] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:12:0) after 6m24s
[05:50:08.168] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:12:0)
[05:50:08.190] Build status: STARTED
[05:50:08.190] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:13:0) after 2m20s
[05:52:28.206] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:13:0)
[05:52:28.228] Build status: STARTED
[05:52:28.228] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:14:0) after 1m26s
[05:53:54.245] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:14:0)
[05:53:54.366] Build status: STARTED
[05:53:54.366] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:15:0) after 8m51s
[06:02:45.339] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:15:0)
[06:02:45.364] Build status: STARTED
[06:02:45.364] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:16:0) after 2m15s
[06:05:00.383] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:16:0)
[06:05:00.414] Build status: STARTED
[06:05:00.414] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:17:0) after 4m22s
[06:09:22.410] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:17:0)
[06:09:22.437] Build status: STARTED
[06:09:22.437] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:18:0) after 3m44s
[06:13:06.546] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:18:0)
[06:13:06.569] Build status: STARTED
[06:13:06.569] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:19:0) after 4m42s
[06:17:48.634] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:19:0)
[06:17:48.664] Build status: STARTED
[06:17:48.664] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:20:0) after 7m20s
[06:25:08.689] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:20:0)
[06:25:08.714] Build status: STARTED
[06:25:08.714] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:21:0) after 3m58s
[06:29:06.731] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:21:0)
[06:29:06.756] Build status: STARTED
[06:29:06.756] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:22:0) after 3m12s
[06:32:18.909] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:22:0)
[06:32:18.931] Build status: STARTED
[06:32:18.931] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:23:0) after 5m49s
[06:38:08.021] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:23:0)
[06:38:08.045] Build status: STARTED
[06:38:08.045] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:24:0) after 4m45s
[06:42:53.038] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:24:0)
[06:42:53.118] Build status: STARTED
[06:42:53.119] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:25:0) after 8m15s
[06:51:08.141] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:25:0)
[06:51:08.180] Build status: STARTED
[06:51:08.180] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:26:0) after 2m43s
[06:53:51.228] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:26:0)
[06:53:51.245] Build status: STARTED
[06:53:51.245] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:27:0) after 7m52s
[07:01:43.332] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:27:0)
[07:01:43.363] Build status: STARTED
[07:01:43.363] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:28:0) after 7m4s
[07:08:47.480] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:28:0)
[07:08:47.543] Build status: STARTED
[07:08:47.543] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:29:0) after 9m12s
[07:17:59.565] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:29:0)
[07:17:59.614] Build status: STARTED
[07:17:59.614] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:30:0) after 9m52s
[07:27:51.695] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:30:0)
[07:27:51.722] Build status: STARTED
[07:27:51.722] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:31:0) after 5m5s
[07:32:57.053] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:31:0)
[07:32:57.221] Build status: STARTED
[07:32:57.221] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:32:0) after 3m57s
[07:36:54.339] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:32:0)
[07:36:54.361] Build status: STARTED
[07:36:54.361] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:33:0) after 6m11s
[07:43:05.427] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:33:0)
[07:43:05.450] Build status: STARTED
[07:43:05.450] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:34:0) after 6m52s
[07:49:57.552] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:34:0)
[07:49:57.709] Build status: STARTED
[07:49:57.709] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:35:0) after 6m52s
[07:56:49.777] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:35:0)
[07:56:49.832] Build status: STARTED
[07:56:49.832] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:36:0) after 2m39s
[07:59:28.913] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:36:0)
[07:59:28.932] Build status: STARTED
[07:59:28.932] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:37:0) after 6m7s
[08:05:35.968] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:37:0)
[08:05:36.017] Build status: STARTED
[08:05:36.017] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:38:0) after 9m12s
[08:14:48.343] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:38:0)
[08:14:48.409] Build status: STARTED
[08:14:48.410] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:39:0) after 4m8s
[08:18:56.456] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:39:0)
[08:18:56.476] Build status: STARTED
[08:18:56.476] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:40:0) after 4m10s
[08:23:06.498] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:40:0)
[08:23:06.532] Build status: STARTED
[08:23:06.532] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:41:0) after 5m11s
[08:28:17.549] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:41:0)
[08:28:17.566] Build status: STARTED
[08:28:17.566] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:42:0) after 8m12s
[08:36:29.669] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:42:0)
[08:36:29.696] Build status: STARTED
[08:36:29.696] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:43:0) after 6m31s
[08:43:00.713] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:43:0)
[08:43:00.736] Build status: STARTED
[08:43:00.736] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:44:0) after 5m11s
[08:48:11.786] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:44:0)
[08:48:11.816] Build status: STARTED
[08:48:11.816] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:45:0) after 6m2s
[08:54:13.837] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:45:0)
[08:54:13.863] Build status: STARTED
[08:54:13.864] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:46:0) after 2m38s
[08:56:51.882] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:46:0)
[08:56:51.935] Build status: STARTED
[08:56:51.935] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:47:0) after 7m7s
[09:03:58.972] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:47:0)
[09:03:58.996] Build status: STARTED
[09:03:58.996] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:48:0) after 4m36s
[09:08:35.112] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:48:0)
[09:08:35.182] Build status: STARTED
[09:08:35.182] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:49:0) after 9m25s
[09:18:00.198] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:49:0)
[09:18:00.218] Build status: STARTED
[09:18:00.218] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:50:0) after 4m45s
[09:22:45.393] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:50:0)
[09:22:45.417] Build status: STARTED
[09:22:45.417] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838406379180279664:51:0) after 9m30s
[09:29:38.206] Received PubSub notification, asking Buildbucket for the build status
[09:29:38.229] Build:
{
"id": "8672976316071025041",
"builder": {
"project": "chromium-m151",
"bucket": "ci",
"builder": "win32-official"
},
"number": 275,
"createdBy": "project:chromium-m151",
"createTime": "2026-08-20T05:04:22.771241573Z",
"startTime": "2026-08-20T05:04:27.351187485Z",
"endTime": "2026-08-20T09:29:37.908743267Z",
"updateTime": "2026-08-20T09:29:37.908743267Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "66241bfafcee04775298657d97015ea5e738f237",
"ref": "refs/branch-heads/7922"
}
}
}
[09:29:38.229] Invocation finished in 4h25m17.80697943s with status SUCCEEDED