[07:12:13.486] New invocation is queued and will start shortly
[07:12:14.869] Starting the invocation (attempt 1)
[07:12:14.897] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/037fce43cb469bc2ba93cd3135769b46a765467e"
[07:12:14.897] Popped gitiles commit info from properties and tags
[07:12:14.897] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:12:14.897] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:12:14.897] Buildbucket request:
{
"requestId": "8874818313889367584",
"builder": {
"project": "chromium-m139",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8874818313889367584",
"job": "chromium-m139/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "76a05d9fbb29eb59f28ce4e0a1c0c1e2c81399e1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@76a05d9fbb29eb59f28ce4e0a1c0c1e2c81399e1",
"title": "76a05d9fbb29eb59f28ce4e0a1c0c1e2c81399e1",
"url": "https://chromium.googlesource.com/chromium/src/+/76a05d9fbb29eb59f28ce4e0a1c0c1e2c81399e1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b15eaf78ff6c25c60301ee440e83d6374568d7a8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@b15eaf78ff6c25c60301ee440e83d6374568d7a8",
"title": "b15eaf78ff6c25c60301ee440e83d6374568d7a8",
"url": "https://chromium.googlesource.com/chromium/src/+/b15eaf78ff6c25c60301ee440e83d6374568d7a8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "45dd8e15144e65e4f36d33cbf0fb6ad6af3e2973"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@45dd8e15144e65e4f36d33cbf0fb6ad6af3e2973",
"title": "45dd8e15144e65e4f36d33cbf0fb6ad6af3e2973",
"url": "https://chromium.googlesource.com/chromium/src/+/45dd8e15144e65e4f36d33cbf0fb6ad6af3e2973"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f18405dd941f9920dfef5178d818a88fcb21342c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@f18405dd941f9920dfef5178d818a88fcb21342c",
"title": "f18405dd941f9920dfef5178d818a88fcb21342c",
"url": "https://chromium.googlesource.com/chromium/src/+/f18405dd941f9920dfef5178d818a88fcb21342c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "037fce43cb469bc2ba93cd3135769b46a765467e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@037fce43cb469bc2ba93cd3135769b46a765467e",
"title": "037fce43cb469bc2ba93cd3135769b46a765467e",
"url": "https://chromium.googlesource.com/chromium/src/+/037fce43cb469bc2ba93cd3135769b46a765467e"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "037fce43cb469bc2ba93cd3135769b46a765467e",
"ref": "refs/branch-heads/7258"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8874818313889367584"
},
{
"key": "scheduler_job_id",
"value": "chromium-m139/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[07:12:15.437] Scheduled build:
{
"id": "8709388251691625953",
"builder": {
"project": "chromium-m139",
"bucket": "ci",
"builder": "win32-official"
},
"number": 134,
"createdBy": "project:chromium-m139",
"createTime": "2025-07-14T07:12:14.959102609Z",
"updateTime": "2025-07-14T07:12:14.959102609Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "037fce43cb469bc2ba93cd3135769b46a765467e",
"ref": "refs/branch-heads/7258"
}
}
}
[07:12:15.437] Task URL: https://cr-buildbucket.appspot.com/build/8709388251691625953
[07:12:15.437] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:2:0) after 3m42s
[07:12:16.408] Received PubSub notification, asking Buildbucket for the build status
[07:12:16.447] Build status: SCHEDULED
[07:12:19.695] Received PubSub notification, asking Buildbucket for the build status
[07:12:19.727] Build status: STARTED
[07:15:57.552] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:2:0)
[07:15:57.578] Build status: STARTED
[07:15:57.578] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:5:0) after 3m47s
[07:19:44.601] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:5:0)
[07:19:44.620] Build status: STARTED
[07:19:44.620] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:6:0) after 8m30s
[07:28:14.651] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:6:0)
[07:28:14.676] Build status: STARTED
[07:28:14.676] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:7:0) after 6m48s
[07:35:02.800] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:7:0)
[07:35:02.840] Build status: STARTED
[07:35:02.840] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:8:0) after 1m24s
[07:36:26.862] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:8:0)
[07:36:26.891] Build status: STARTED
[07:36:26.891] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:9:0) after 1m4s
[07:37:30.914] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:9:0)
[07:37:30.937] Build status: STARTED
[07:37:30.937] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:10:0) after 7m24s
[07:44:55.052] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:10:0)
[07:44:55.072] Build status: STARTED
[07:44:55.072] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:11:0) after 6m7s
[07:51:02.087] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:11:0)
[07:51:02.132] Build status: STARTED
[07:51:02.133] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:12:0) after 1m57s
[07:52:59.162] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:12:0)
[07:52:59.197] Build status: STARTED
[07:52:59.197] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:13:0) after 5m53s
[07:58:52.777] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:13:0)
[07:58:52.797] Build status: STARTED
[07:58:52.797] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:14:0) after 1m8s
[08:00:00.881] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:14:0)
[08:00:00.902] Build status: STARTED
[08:00:00.902] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:15:0) after 9m17s
[08:09:17.919] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:15:0)
[08:09:17.965] Build status: STARTED
[08:09:17.966] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:16:0) after 8m12s
[08:17:30.022] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:16:0)
[08:17:30.050] Build status: STARTED
[08:17:30.050] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:17:0) after 8m8s
[08:25:38.066] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:17:0)
[08:25:38.089] Build status: STARTED
[08:25:38.089] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:18:0) after 2m47s
[08:28:25.134] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:18:0)
[08:28:25.160] Build status: STARTED
[08:28:25.160] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:19:0) after 9m49s
[08:38:14.311] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:19:0)
[08:38:14.328] Build status: STARTED
[08:38:14.328] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:20:0) after 6m3s
[08:44:17.399] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:20:0)
[08:44:17.452] Build status: STARTED
[08:44:17.452] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:21:0) after 3m7s
[08:47:24.455] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:21:0)
[08:47:24.477] Build status: STARTED
[08:47:24.477] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:22:0) after 1m21s
[08:48:45.498] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:22:0)
[08:48:45.515] Build status: STARTED
[08:48:45.515] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:23:0) after 2m22s
[08:51:07.735] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:23:0)
[08:51:07.751] Build status: STARTED
[08:51:07.751] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:24:0) after 2m6s
[08:53:13.773] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:24:0)
[08:53:13.800] Build status: STARTED
[08:53:13.800] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:25:0) after 3m28s
[08:56:41.815] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:25:0)
[08:56:41.870] Build status: STARTED
[08:56:41.870] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:26:0) after 4m18s
[09:00:59.942] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:26:0)
[09:00:59.999] Build status: STARTED
[09:00:59.999] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:27:0) after 5m50s
[09:06:49.984] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:27:0)
[09:06:50.002] Build status: STARTED
[09:06:50.002] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:28:0) after 8m11s
[09:15:01.122] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:28:0)
[09:15:01.419] Build status: STARTED
[09:15:01.419] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:29:0) after 6m36s
[09:21:37.494] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:29:0)
[09:21:37.528] Build status: STARTED
[09:21:37.528] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:30:0) after 3m49s
[09:25:26.560] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:30:0)
[09:25:26.583] Build status: STARTED
[09:25:26.583] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:31:0) after 2m56s
[09:28:22.611] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:31:0)
[09:28:22.634] Build status: STARTED
[09:28:22.634] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:32:0) after 3m6s
[09:31:28.672] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:32:0)
[09:31:28.699] Build status: STARTED
[09:31:28.700] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:33:0) after 9m54s
[09:41:22.777] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:33:0)
[09:41:22.816] Build status: STARTED
[09:41:22.816] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:34:0) after 6m15s
[09:47:37.873] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:34:0)
[09:47:37.894] Build status: STARTED
[09:47:37.894] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:35:0) after 5m27s
[09:53:04.922] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:35:0)
[09:53:04.961] Build status: STARTED
[09:53:04.961] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:36:0) after 1m12s
[09:54:16.997] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:36:0)
[09:54:17.012] Build status: STARTED
[09:54:17.012] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:37:0) after 1m0s
[09:55:17.091] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:37:0)
[09:55:17.119] Build status: STARTED
[09:55:17.119] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:38:0) after 8m55s
[10:04:12.154] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:38:0)
[10:04:12.176] Build status: STARTED
[10:04:12.176] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:39:0) after 2m48s
[10:07:00.289] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:39:0)
[10:07:00.324] Build status: STARTED
[10:07:00.324] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:40:0) after 6m48s
[10:13:48.340] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:40:0)
[10:13:48.356] Build status: STARTED
[10:13:48.356] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:41:0) after 9m37s
[10:23:25.427] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:41:0)
[10:23:25.442] Build status: STARTED
[10:23:25.442] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8874818313889367584:42:0) after 8m50s
[10:28:43.002] Received PubSub notification, asking Buildbucket for the build status
[10:28:43.021] Build:
{
"id": "8709388251691625953",
"builder": {
"project": "chromium-m139",
"bucket": "ci",
"builder": "win32-official"
},
"number": 134,
"createdBy": "project:chromium-m139",
"createTime": "2025-07-14T07:12:14.959102609Z",
"startTime": "2025-07-14T07:12:19.427110827Z",
"endTime": "2025-07-14T10:28:42.756372490Z",
"updateTime": "2025-07-14T10:28:42.756372490Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "037fce43cb469bc2ba93cd3135769b46a765467e",
"ref": "refs/branch-heads/7258"
}
}
}
[10:28:43.021] Invocation finished in 3h16m29.542411627s with status SUCCEEDED