[02:35:29.710] New invocation is queued and will start shortly
[02:35:30.808] Starting the invocation (attempt 1)
[02:35:30.837] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/375825992e85ef1373f8f9ac2e2b7c83bcd6ea8a"
[02:35:30.837] Popped gitiles commit info from properties and tags
[02:35:30.837] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:35:30.837] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:35:30.837] Buildbucket request:
{
"requestId": "8840137086083838048",
"builder": {
"project": "chromium-m152",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8840137086083838048",
"job": "chromium-m152/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7977",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cb74c34cb688971eaf26e879a19c0386527a85e7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7977@cb74c34cb688971eaf26e879a19c0386527a85e7",
"title": "cb74c34cb688971eaf26e879a19c0386527a85e7",
"url": "https://chromium.googlesource.com/chromium/src/+/cb74c34cb688971eaf26e879a19c0386527a85e7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7977",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8ef46dfa97ceba030a6000985fbf8f9ebb89b39d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7977@8ef46dfa97ceba030a6000985fbf8f9ebb89b39d",
"title": "8ef46dfa97ceba030a6000985fbf8f9ebb89b39d",
"url": "https://chromium.googlesource.com/chromium/src/+/8ef46dfa97ceba030a6000985fbf8f9ebb89b39d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7977",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a0dde22178339997e5d8869f77a077234fcefd23"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7977@a0dde22178339997e5d8869f77a077234fcefd23",
"title": "a0dde22178339997e5d8869f77a077234fcefd23",
"url": "https://chromium.googlesource.com/chromium/src/+/a0dde22178339997e5d8869f77a077234fcefd23"
},
{
"gitiles": {
"ref": "refs/branch-heads/7977",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4f5379f07bdabb6b109451f005b3d3d6d70e7b5b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7977@4f5379f07bdabb6b109451f005b3d3d6d70e7b5b",
"title": "4f5379f07bdabb6b109451f005b3d3d6d70e7b5b",
"url": "https://chromium.googlesource.com/chromium/src/+/4f5379f07bdabb6b109451f005b3d3d6d70e7b5b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7977",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6c777ef1fea5238abb5bc43cff43e8ebe898507c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7977@6c777ef1fea5238abb5bc43cff43e8ebe898507c",
"title": "6c777ef1fea5238abb5bc43cff43e8ebe898507c",
"url": "https://chromium.googlesource.com/chromium/src/+/6c777ef1fea5238abb5bc43cff43e8ebe898507c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7977",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "75c804d3fb1f4f4d74e03d3b086644f0d6fbd40f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7977@75c804d3fb1f4f4d74e03d3b086644f0d6fbd40f",
"title": "75c804d3fb1f4f4d74e03d3b086644f0d6fbd40f",
"url": "https://chromium.googlesource.com/chromium/src/+/75c804d3fb1f4f4d74e03d3b086644f0d6fbd40f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7977",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c7be793a1dc86cf39967fbd0c145cfa9d8835490"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7977@c7be793a1dc86cf39967fbd0c145cfa9d8835490",
"title": "c7be793a1dc86cf39967fbd0c145cfa9d8835490",
"url": "https://chromium.googlesource.com/chromium/src/+/c7be793a1dc86cf39967fbd0c145cfa9d8835490"
},
{
"gitiles": {
"ref": "refs/branch-heads/7977",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "20e0d9c0bafdf5b2cf66ff9baef6922cfa7333b5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7977@20e0d9c0bafdf5b2cf66ff9baef6922cfa7333b5",
"title": "20e0d9c0bafdf5b2cf66ff9baef6922cfa7333b5",
"url": "https://chromium.googlesource.com/chromium/src/+/20e0d9c0bafdf5b2cf66ff9baef6922cfa7333b5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7977",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5e8022393bc986f37be2ee7897aafdd65aecf3f0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7977@5e8022393bc986f37be2ee7897aafdd65aecf3f0",
"title": "5e8022393bc986f37be2ee7897aafdd65aecf3f0",
"url": "https://chromium.googlesource.com/chromium/src/+/5e8022393bc986f37be2ee7897aafdd65aecf3f0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7977",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ae2697c330cc02a119cb64fd5c2ba192d56e0db9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7977@ae2697c330cc02a119cb64fd5c2ba192d56e0db9",
"title": "ae2697c330cc02a119cb64fd5c2ba192d56e0db9",
"url": "https://chromium.googlesource.com/chromium/src/+/ae2697c330cc02a119cb64fd5c2ba192d56e0db9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7977",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "91f89cae59a3e593f7955c8e25dde872c7877c85"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7977@91f89cae59a3e593f7955c8e25dde872c7877c85",
"title": "91f89cae59a3e593f7955c8e25dde872c7877c85",
"url": "https://chromium.googlesource.com/chromium/src/+/91f89cae59a3e593f7955c8e25dde872c7877c85"
},
{
"gitiles": {
"ref": "refs/branch-heads/7977",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "375825992e85ef1373f8f9ac2e2b7c83bcd6ea8a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7977@375825992e85ef1373f8f9ac2e2b7c83bcd6ea8a",
"title": "375825992e85ef1373f8f9ac2e2b7c83bcd6ea8a",
"url": "https://chromium.googlesource.com/chromium/src/+/375825992e85ef1373f8f9ac2e2b7c83bcd6ea8a"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "375825992e85ef1373f8f9ac2e2b7c83bcd6ea8a",
"ref": "refs/branch-heads/7977"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8840137086083838048"
},
{
"key": "scheduler_job_id",
"value": "chromium-m152/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:35:31.426] Scheduled build:
{
"id": "8674707024175473137",
"builder": {
"project": "chromium-m152",
"bucket": "ci",
"builder": "win-official"
},
"number": 23,
"createdBy": "project:chromium-m152",
"createTime": "2026-08-01T02:35:30.902666198Z",
"updateTime": "2026-08-01T02:35:30.902666198Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "375825992e85ef1373f8f9ac2e2b7c83bcd6ea8a",
"ref": "refs/branch-heads/7977"
}
}
}
[02:35:31.426] Task URL: https://cr-buildbucket.appspot.com/build/8674707024175473137
[02:35:31.426] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:2:0) after 4m28s
[02:35:32.348] Received PubSub notification, asking Buildbucket for the build status
[02:35:32.378] Build status: SCHEDULED
[02:35:35.985] Received PubSub notification, asking Buildbucket for the build status
[02:35:36.005] Build status: STARTED
[02:39:59.472] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:2:0)
[02:39:59.491] Build status: STARTED
[02:39:59.491] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:5:0) after 6m47s
[02:46:46.558] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:5:0)
[02:46:46.586] Build status: STARTED
[02:46:46.586] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:6:0) after 5m7s
[02:51:53.671] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:6:0)
[02:51:53.699] Build status: STARTED
[02:51:53.699] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:7:0) after 3m59s
[02:55:52.717] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:7:0)
[02:55:52.740] Build status: STARTED
[02:55:52.740] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:8:0) after 4m39s
[03:00:31.777] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:8:0)
[03:00:31.803] Build status: STARTED
[03:00:31.803] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:9:0) after 5m46s
[03:06:17.881] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:9:0)
[03:06:17.910] Build status: STARTED
[03:06:17.910] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:10:0) after 2m49s
[03:09:06.993] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:10:0)
[03:09:07.150] Build status: STARTED
[03:09:07.150] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:11:0) after 9m23s
[03:18:30.165] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:11:0)
[03:18:30.184] Build status: STARTED
[03:18:30.184] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:12:0) after 1m55s
[03:20:25.218] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:12:0)
[03:20:25.235] Build status: STARTED
[03:20:25.235] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:13:0) after 9m34s
[03:29:59.493] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:13:0)
[03:29:59.510] Build status: STARTED
[03:29:59.510] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:14:0) after 5m12s
[03:35:11.545] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:14:0)
[03:35:11.566] Build status: STARTED
[03:35:11.566] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:15:0) after 4m53s
[03:40:04.540] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:15:0)
[03:40:04.627] Build status: STARTED
[03:40:04.627] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:16:0) after 4m1s
[03:44:05.606] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:16:0)
[03:44:05.630] Build status: STARTED
[03:44:05.630] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:17:0) after 3m22s
[03:47:27.685] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:17:0)
[03:47:27.752] Build status: STARTED
[03:47:27.752] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:18:0) after 2m50s
[03:50:17.841] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:18:0)
[03:50:17.856] Build status: STARTED
[03:50:17.856] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:19:0) after 4m50s
[03:55:07.881] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:19:0)
[03:55:07.901] Build status: STARTED
[03:55:07.901] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:20:0) after 7m56s
[04:03:04.018] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:20:0)
[04:03:04.051] Build status: STARTED
[04:03:04.051] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:21:0) after 1m24s
[04:04:28.162] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:21:0)
[04:04:28.181] Build status: STARTED
[04:04:28.181] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:22:0) after 8m49s
[04:13:17.248] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:22:0)
[04:13:17.266] Build status: STARTED
[04:13:17.266] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:23:0) after 2m57s
[04:16:14.282] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:23:0)
[04:16:14.302] Build status: STARTED
[04:16:14.302] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:24:0) after 7m26s
[04:23:40.364] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:24:0)
[04:23:40.431] Build status: STARTED
[04:23:40.431] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:25:0) after 4m58s
[04:28:38.448] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:25:0)
[04:28:38.469] Build status: STARTED
[04:28:38.469] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:26:0) after 9m46s
[04:38:24.486] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:26:0)
[04:38:24.527] Build status: STARTED
[04:38:24.527] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:27:0) after 9m22s
[04:47:46.809] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:27:0)
[04:47:46.827] Build status: STARTED
[04:47:46.827] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:28:0) after 3m22s
[04:51:08.816] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:28:0)
[04:51:08.835] Build status: STARTED
[04:51:08.835] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:29:0) after 6m26s
[04:57:34.857] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:29:0)
[04:57:34.974] Build status: STARTED
[04:57:34.974] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:30:0) after 2m58s
[05:00:33.122] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:30:0)
[05:00:33.147] Build status: STARTED
[05:00:33.147] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:31:0) after 1m51s
[05:02:24.197] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:31:0)
[05:02:24.220] Build status: STARTED
[05:02:24.220] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:32:0) after 4m52s
[05:07:16.265] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:32:0)
[05:07:16.287] Build status: STARTED
[05:07:16.287] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:33:0) after 4m31s
[05:11:47.512] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:33:0)
[05:11:47.569] Build status: STARTED
[05:11:47.569] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:34:0) after 1m23s
[05:13:10.576] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:34:0)
[05:13:10.595] Build status: STARTED
[05:13:10.595] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:35:0) after 5m10s
[05:18:20.630] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:35:0)
[05:18:20.656] Build status: STARTED
[05:18:20.656] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:36:0) after 2m51s
[05:21:11.758] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:36:0)
[05:21:11.778] Build status: STARTED
[05:21:11.778] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:37:0) after 8m39s
[05:29:50.771] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:37:0)
[05:29:50.792] Build status: STARTED
[05:29:50.792] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:38:0) after 9m16s
[05:39:06.848] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:38:0)
[05:39:06.870] Build status: STARTED
[05:39:06.870] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:39:0) after 9m10s
[05:48:16.949] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:39:0)
[05:48:16.967] Build status: STARTED
[05:48:16.967] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:40:0) after 6m15s
[05:54:32.112] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:40:0)
[05:54:32.138] Build status: STARTED
[05:54:32.138] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:41:0) after 2m30s
[05:57:02.162] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:41:0)
[05:57:02.197] Build status: STARTED
[05:57:02.197] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:42:0) after 7m7s
[06:04:09.235] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:42:0)
[06:04:09.257] Build status: STARTED
[06:04:09.257] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:43:0) after 1m24s
[06:05:33.276] Handling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:43:0)
[06:05:33.297] Build status: STARTED
[06:05:33.297] Scheduling timer "check-buildbucket-build-status" (chromium-m152/win-official:8840137086083838048:44:0) after 9m55s
[06:11:15.068] Received PubSub notification, asking Buildbucket for the build status
[06:11:15.100] Build:
{
"id": "8674707024175473137",
"builder": {
"project": "chromium-m152",
"bucket": "ci",
"builder": "win-official"
},
"number": 23,
"createdBy": "project:chromium-m152",
"createTime": "2026-08-01T02:35:30.902666198Z",
"startTime": "2026-08-01T02:35:35.778237783Z",
"endTime": "2026-08-01T06:11:14.650069152Z",
"updateTime": "2026-08-01T06:11:14.650069152Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "375825992e85ef1373f8f9ac2e2b7c83bcd6ea8a",
"ref": "refs/branch-heads/7977"
}
}
}
[06:11:15.100] Invocation finished in 3h35m45.400451025s with status SUCCEEDED