[02:33:55.072] New invocation is queued and will start shortly
[02:33:56.449] Starting the invocation (attempt 1)
[02:33:56.483] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/83a64ca75a735dedf11309ee42bb037a8205c402"
[02:33:56.483] Popped gitiles commit info from properties and tags
[02:33:56.483] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:33:56.483] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:33:56.483] Buildbucket request:
{
"requestId": "8843308079136202288",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8843308079136202288",
"job": "chromium-m149/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "60148a80783065a692269786476382190398bbb2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@60148a80783065a692269786476382190398bbb2",
"title": "60148a80783065a692269786476382190398bbb2",
"url": "https://chromium.googlesource.com/chromium/src/+/60148a80783065a692269786476382190398bbb2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5c9d2f457482d7d11021c7701f293b44d88eb685"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@5c9d2f457482d7d11021c7701f293b44d88eb685",
"title": "5c9d2f457482d7d11021c7701f293b44d88eb685",
"url": "https://chromium.googlesource.com/chromium/src/+/5c9d2f457482d7d11021c7701f293b44d88eb685"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bc188583386710fa9c28ff03991d1beccc0ce2df"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@bc188583386710fa9c28ff03991d1beccc0ce2df",
"title": "bc188583386710fa9c28ff03991d1beccc0ce2df",
"url": "https://chromium.googlesource.com/chromium/src/+/bc188583386710fa9c28ff03991d1beccc0ce2df"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "180e5771ec66d94e59abab0a256754335c5f384c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@180e5771ec66d94e59abab0a256754335c5f384c",
"title": "180e5771ec66d94e59abab0a256754335c5f384c",
"url": "https://chromium.googlesource.com/chromium/src/+/180e5771ec66d94e59abab0a256754335c5f384c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "412798ea06d81c3634c3ddd30b5e1515324bd4ec"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@412798ea06d81c3634c3ddd30b5e1515324bd4ec",
"title": "412798ea06d81c3634c3ddd30b5e1515324bd4ec",
"url": "https://chromium.googlesource.com/chromium/src/+/412798ea06d81c3634c3ddd30b5e1515324bd4ec"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "aca15c86620f33df93ba430b8eb960224def3d26"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@aca15c86620f33df93ba430b8eb960224def3d26",
"title": "aca15c86620f33df93ba430b8eb960224def3d26",
"url": "https://chromium.googlesource.com/chromium/src/+/aca15c86620f33df93ba430b8eb960224def3d26"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "83a64ca75a735dedf11309ee42bb037a8205c402"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@83a64ca75a735dedf11309ee42bb037a8205c402",
"title": "83a64ca75a735dedf11309ee42bb037a8205c402",
"url": "https://chromium.googlesource.com/chromium/src/+/83a64ca75a735dedf11309ee42bb037a8205c402"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "83a64ca75a735dedf11309ee42bb037a8205c402",
"ref": "refs/branch-heads/7827"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8843308079136202288"
},
{
"key": "scheduler_job_id",
"value": "chromium-m149/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:33:56.979] Scheduled build:
{
"id": "8677878016928120305",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win32-official"
},
"number": 284,
"createdBy": "project:chromium-m149",
"createTime": "2026-06-27T02:33:56.557831630Z",
"updateTime": "2026-06-27T02:33:56.557831630Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "83a64ca75a735dedf11309ee42bb037a8205c402",
"ref": "refs/branch-heads/7827"
}
}
}
[02:33:56.979] Task URL: https://cr-buildbucket.appspot.com/build/8677878016928120305
[02:33:56.979] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:2:0) after 5m53s
[02:34:03.355] Received PubSub notification, asking Buildbucket for the build status
[02:34:03.375] Build status: SCHEDULED
[02:34:20.556] Received PubSub notification, asking Buildbucket for the build status
[02:34:20.574] Build status: STARTED
[02:39:49.995] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:2:0)
[02:39:50.024] Build status: STARTED
[02:39:50.025] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:5:0) after 3m12s
[02:43:02.044] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:5:0)
[02:43:02.059] Build status: STARTED
[02:43:02.059] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:6:0) after 4m8s
[02:47:10.073] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:6:0)
[02:47:10.095] Build status: STARTED
[02:47:10.095] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:7:0) after 7m38s
[02:54:48.110] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:7:0)
[02:54:48.186] Build status: STARTED
[02:54:48.186] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:8:0) after 1m5s
[02:55:53.202] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:8:0)
[02:55:53.220] Build status: STARTED
[02:55:53.220] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:9:0) after 3m2s
[02:58:55.236] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:9:0)
[02:58:55.265] Build status: STARTED
[02:58:55.265] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:10:0) after 9m21s
[03:08:16.277] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:10:0)
[03:08:16.302] Build status: STARTED
[03:08:16.302] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:11:0) after 1m27s
[03:09:43.322] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:11:0)
[03:09:43.342] Build status: STARTED
[03:09:43.342] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:12:0) after 4m20s
[03:14:03.386] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:12:0)
[03:14:03.407] Build status: STARTED
[03:14:03.407] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:13:0) after 1m2s
[03:15:05.419] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:13:0)
[03:15:05.496] Build status: STARTED
[03:15:05.496] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:14:0) after 5m34s
[03:20:39.574] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:14:0)
[03:20:39.658] Build status: STARTED
[03:20:39.658] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:15:0) after 8m44s
[03:29:23.697] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:15:0)
[03:29:23.757] Build status: STARTED
[03:29:23.757] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:16:0) after 7m4s
[03:36:27.788] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:16:0)
[03:36:27.805] Build status: STARTED
[03:36:27.805] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:17:0) after 9m45s
[03:46:13.018] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:17:0)
[03:46:13.088] Build status: STARTED
[03:46:13.088] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:18:0) after 8m0s
[03:54:13.155] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:18:0)
[03:54:13.174] Build status: STARTED
[03:54:13.174] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:19:0) after 6m56s
[04:01:09.192] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:19:0)
[04:01:09.210] Build status: STARTED
[04:01:09.211] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:20:0) after 4m34s
[04:05:43.226] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:20:0)
[04:05:43.244] Build status: STARTED
[04:05:43.244] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:21:0) after 8m37s
[04:14:20.310] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:21:0)
[04:14:20.342] Build status: STARTED
[04:14:20.342] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:22:0) after 4m30s
[04:18:50.416] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:22:0)
[04:18:50.437] Build status: STARTED
[04:18:50.437] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:23:0) after 4m16s
[04:23:06.475] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:23:0)
[04:23:06.506] Build status: STARTED
[04:23:06.506] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:24:0) after 5m50s
[04:28:56.524] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:24:0)
[04:28:56.540] Build status: STARTED
[04:28:56.540] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:25:0) after 9m2s
[04:37:58.557] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:25:0)
[04:37:58.708] Build status: STARTED
[04:37:58.708] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:26:0) after 4m54s
[04:42:52.739] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:26:0)
[04:42:52.761] Build status: STARTED
[04:42:52.761] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:27:0) after 7m30s
[04:50:22.828] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:27:0)
[04:50:22.861] Build status: STARTED
[04:50:22.861] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:28:0) after 5m12s
[04:55:34.969] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:28:0)
[04:55:34.987] Build status: STARTED
[04:55:34.987] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:29:0) after 3m0s
[04:58:35.125] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:29:0)
[04:58:35.151] Build status: STARTED
[04:58:35.151] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:30:0) after 1m32s
[05:00:07.373] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:30:0)
[05:00:07.419] Build status: STARTED
[05:00:07.419] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:31:0) after 1m49s
[05:01:56.550] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:31:0)
[05:01:56.589] Build status: STARTED
[05:01:56.589] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:32:0) after 5m54s
[05:07:50.604] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:32:0)
[05:07:50.625] Build status: STARTED
[05:07:50.625] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:33:0) after 5m4s
[05:12:54.690] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:33:0)
[05:12:54.710] Build status: STARTED
[05:12:54.710] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:34:0) after 5m25s
[05:18:19.732] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:34:0)
[05:18:19.753] Build status: STARTED
[05:18:19.753] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:35:0) after 7m54s
[05:26:13.866] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:35:0)
[05:26:13.887] Build status: STARTED
[05:26:13.887] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:36:0) after 5m39s
[05:31:52.879] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:36:0)
[05:31:52.904] Build status: STARTED
[05:31:52.904] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:37:0) after 7m25s
[05:39:17.921] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:37:0)
[05:39:17.944] Build status: STARTED
[05:39:17.944] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:38:0) after 2m50s
[05:42:07.957] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:38:0)
[05:42:07.977] Build status: STARTED
[05:42:07.978] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:39:0) after 4m5s
[05:46:12.964] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:39:0)
[05:46:12.992] Build status: STARTED
[05:46:12.992] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:40:0) after 7m35s
[05:53:48.033] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:40:0)
[05:53:48.051] Build status: STARTED
[05:53:48.051] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:41:0) after 1m14s
[05:55:02.868] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:41:0)
[05:55:02.885] Build status: STARTED
[05:55:02.885] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:42:0) after 5m48s
[06:00:50.919] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:42:0)
[06:00:50.942] Build status: STARTED
[06:00:50.943] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:43:0) after 1m51s
[06:02:41.962] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:43:0)
[06:02:41.981] Build status: STARTED
[06:02:41.981] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:44:0) after 1m36s
[06:04:18.010] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:44:0)
[06:04:18.102] Build status: STARTED
[06:04:18.102] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:45:0) after 5m59s
[06:10:17.458] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:45:0)
[06:10:17.502] Build status: STARTED
[06:10:17.502] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:46:0) after 5m37s
[06:15:54.582] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:46:0)
[06:15:54.601] Build status: STARTED
[06:15:54.601] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:47:0) after 8m53s
[06:24:47.594] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:47:0)
[06:24:47.615] Build status: STARTED
[06:24:47.615] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:48:0) after 5m4s
[06:29:51.731] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:48:0)
[06:29:51.753] Build status: STARTED
[06:29:51.753] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843308079136202288:49:0) after 2m7s
[06:31:51.515] Received PubSub notification, asking Buildbucket for the build status
[06:31:51.539] Build:
{
"id": "8677878016928120305",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win32-official"
},
"number": 284,
"createdBy": "project:chromium-m149",
"createTime": "2026-06-27T02:33:56.557831630Z",
"startTime": "2026-06-27T02:34:08.204326631Z",
"endTime": "2026-06-27T06:31:51.163830061Z",
"updateTime": "2026-06-27T06:31:51.163830061Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "83a64ca75a735dedf11309ee42bb037a8205c402",
"ref": "refs/branch-heads/7827"
}
}
}
[06:31:51.539] Invocation finished in 3h57m56.4724906s with status SUCCEEDED