[01:19:38.816] New invocation is queued and will start shortly
[01:19:39.992] Starting the invocation (attempt 1)
[01:19:40.020] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/34c12a9053938141656d340ede49b9fc573cc752"
[01:19:40.020] Popped gitiles commit info from properties and tags
[01:19:40.020] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:19:40.020] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:19:40.020] Buildbucket request:
{
"requestId": "8840957230733048016",
"builder": {
"project": "chromium-m151",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8840957230733048016",
"job": "chromium-m151/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ff8e7f3fedc098d176c1a26f7750f5725328e590"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@ff8e7f3fedc098d176c1a26f7750f5725328e590",
"title": "ff8e7f3fedc098d176c1a26f7750f5725328e590",
"url": "https://chromium.googlesource.com/chromium/src/+/ff8e7f3fedc098d176c1a26f7750f5725328e590"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c25d10dad0ed006987fb43248e8b957ab6ff10d1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@c25d10dad0ed006987fb43248e8b957ab6ff10d1",
"title": "c25d10dad0ed006987fb43248e8b957ab6ff10d1",
"url": "https://chromium.googlesource.com/chromium/src/+/c25d10dad0ed006987fb43248e8b957ab6ff10d1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8fae6bbe9ca7e6759d39ff1987ad257cebfdc7e1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@8fae6bbe9ca7e6759d39ff1987ad257cebfdc7e1",
"title": "8fae6bbe9ca7e6759d39ff1987ad257cebfdc7e1",
"url": "https://chromium.googlesource.com/chromium/src/+/8fae6bbe9ca7e6759d39ff1987ad257cebfdc7e1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4a193936de1f026479e6fb118db0c02349d267a5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@4a193936de1f026479e6fb118db0c02349d267a5",
"title": "4a193936de1f026479e6fb118db0c02349d267a5",
"url": "https://chromium.googlesource.com/chromium/src/+/4a193936de1f026479e6fb118db0c02349d267a5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "04cf6dce413684d98548415722a6e89fec491cf9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@04cf6dce413684d98548415722a6e89fec491cf9",
"title": "04cf6dce413684d98548415722a6e89fec491cf9",
"url": "https://chromium.googlesource.com/chromium/src/+/04cf6dce413684d98548415722a6e89fec491cf9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9cde27d99595fb56e92a3d83b8543ff3082d9914"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@9cde27d99595fb56e92a3d83b8543ff3082d9914",
"title": "9cde27d99595fb56e92a3d83b8543ff3082d9914",
"url": "https://chromium.googlesource.com/chromium/src/+/9cde27d99595fb56e92a3d83b8543ff3082d9914"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "34c12a9053938141656d340ede49b9fc573cc752"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@34c12a9053938141656d340ede49b9fc573cc752",
"title": "34c12a9053938141656d340ede49b9fc573cc752",
"url": "https://chromium.googlesource.com/chromium/src/+/34c12a9053938141656d340ede49b9fc573cc752"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "34c12a9053938141656d340ede49b9fc573cc752",
"ref": "refs/branch-heads/7922"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8840957230733048016"
},
{
"key": "scheduler_job_id",
"value": "chromium-m151/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:19:40.612] Scheduled build:
{
"id": "8675527168743519793",
"builder": {
"project": "chromium-m151",
"bucket": "ci",
"builder": "win-official"
},
"number": 152,
"createdBy": "project:chromium-m151",
"createTime": "2026-07-23T01:19:40.092779474Z",
"updateTime": "2026-07-23T01:19:40.092779474Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "34c12a9053938141656d340ede49b9fc573cc752",
"ref": "refs/branch-heads/7922"
}
}
}
[01:19:40.612] Task URL: https://cr-buildbucket.appspot.com/build/8675527168743519793
[01:19:40.612] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:2:0) after 6m37s
[01:19:40.724] Received PubSub notification, asking Buildbucket for the build status
[01:19:40.800] Build status: SCHEDULED
[01:19:51.183] Received PubSub notification, asking Buildbucket for the build status
[01:19:51.312] Build status: STARTED
[01:26:17.677] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:2:0)
[01:26:17.696] Build status: STARTED
[01:26:17.696] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:5:0) after 6m24s
[01:32:41.814] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:5:0)
[01:32:41.830] Build status: STARTED
[01:32:41.830] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:6:0) after 8m1s
[01:40:42.891] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:6:0)
[01:40:42.911] Build status: STARTED
[01:40:42.911] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:7:0) after 3m36s
[01:44:19.006] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:7:0)
[01:44:19.041] Build status: STARTED
[01:44:19.041] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:8:0) after 1m21s
[01:45:40.022] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:8:0)
[01:45:40.051] Build status: STARTED
[01:45:40.051] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:9:0) after 7m25s
[01:53:05.098] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:9:0)
[01:53:05.118] Build status: STARTED
[01:53:05.118] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:10:0) after 3m31s
[01:56:36.157] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:10:0)
[01:56:36.181] Build status: STARTED
[01:56:36.181] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:11:0) after 4m53s
[02:01:29.395] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:11:0)
[02:01:29.581] Build status: STARTED
[02:01:29.581] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:12:0) after 6m29s
[02:07:58.614] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:12:0)
[02:07:58.634] Build status: STARTED
[02:07:58.634] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:13:0) after 4m21s
[02:12:19.646] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:13:0)
[02:12:19.669] Build status: STARTED
[02:12:19.669] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:14:0) after 3m44s
[02:16:03.668] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:14:0)
[02:16:03.756] Build status: STARTED
[02:16:03.756] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:15:0) after 2m24s
[02:18:27.771] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:15:0)
[02:18:27.801] Build status: STARTED
[02:18:27.801] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:16:0) after 3m55s
[02:22:23.037] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:16:0)
[02:22:23.058] Build status: STARTED
[02:22:23.059] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:17:0) after 6m36s
[02:28:59.075] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:17:0)
[02:28:59.098] Build status: STARTED
[02:28:59.098] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:18:0) after 1m21s
[02:30:20.063] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:18:0)
[02:30:20.122] Build status: STARTED
[02:30:20.122] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:19:0) after 6m44s
[02:37:04.196] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:19:0)
[02:37:04.223] Build status: STARTED
[02:37:04.224] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:20:0) after 7m16s
[02:44:20.241] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:20:0)
[02:44:20.274] Build status: STARTED
[02:44:20.274] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:21:0) after 1m26s
[02:45:46.329] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:21:0)
[02:45:46.406] Build status: STARTED
[02:45:46.407] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:22:0) after 8m51s
[02:54:37.387] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:22:0)
[02:54:37.441] Build status: STARTED
[02:54:37.441] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:23:0) after 5m5s
[02:59:42.456] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:23:0)
[02:59:42.476] Build status: STARTED
[02:59:42.476] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:24:0) after 1m41s
[03:01:23.547] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:24:0)
[03:01:23.681] Build status: STARTED
[03:01:23.681] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:25:0) after 5m6s
[03:06:29.669] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:25:0)
[03:06:29.689] Build status: STARTED
[03:06:29.689] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:26:0) after 6m5s
[03:12:34.759] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:26:0)
[03:12:34.776] Build status: STARTED
[03:12:34.776] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:27:0) after 4m16s
[03:16:50.846] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:27:0)
[03:16:50.873] Build status: STARTED
[03:16:50.873] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:28:0) after 1m4s
[03:17:54.899] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:28:0)
[03:17:54.923] Build status: STARTED
[03:17:54.923] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:29:0) after 8m46s
[03:26:40.936] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:29:0)
[03:26:40.961] Build status: STARTED
[03:26:40.961] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:30:0) after 9m16s
[03:35:57.287] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:30:0)
[03:35:57.340] Build status: STARTED
[03:35:57.340] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:31:0) after 2m35s
[03:38:32.321] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:31:0)
[03:38:32.340] Build status: STARTED
[03:38:32.340] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:32:0) after 6m9s
[03:44:41.355] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:32:0)
[03:44:41.375] Build status: STARTED
[03:44:41.375] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:33:0) after 5m48s
[03:50:29.481] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:33:0)
[03:50:29.499] Build status: STARTED
[03:50:29.499] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:34:0) after 1m26s
[03:51:55.642] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:34:0)
[03:51:55.663] Build status: STARTED
[03:51:55.663] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:35:0) after 2m44s
[03:54:39.718] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:35:0)
[03:54:39.739] Build status: STARTED
[03:54:39.739] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:36:0) after 1m36s
[03:56:15.806] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:36:0)
[03:56:15.856] Build status: STARTED
[03:56:15.856] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:37:0) after 8m57s
[04:05:12.875] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:37:0)
[04:05:12.899] Build status: STARTED
[04:05:12.899] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:38:0) after 6m56s
[04:12:09.017] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:38:0)
[04:12:09.056] Build status: STARTED
[04:12:09.057] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:39:0) after 4m53s
[04:17:02.078] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:39:0)
[04:17:02.099] Build status: STARTED
[04:17:02.099] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:40:0) after 1m54s
[04:18:56.109] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:40:0)
[04:18:56.160] Build status: STARTED
[04:18:56.161] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:41:0) after 4m41s
[04:23:37.279] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:41:0)
[04:23:37.363] Build status: STARTED
[04:23:37.363] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:42:0) after 8m18s
[04:31:55.381] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:42:0)
[04:31:55.397] Build status: STARTED
[04:31:55.397] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:43:0) after 4m25s
[04:36:20.478] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:43:0)
[04:36:20.501] Build status: STARTED
[04:36:20.501] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:44:0) after 1m26s
[04:37:46.577] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:44:0)
[04:37:46.603] Build status: STARTED
[04:37:46.603] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:45:0) after 2m3s
[04:39:49.616] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:45:0)
[04:39:49.636] Build status: STARTED
[04:39:49.636] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:46:0) after 6m18s
[04:46:07.654] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:46:0)
[04:46:07.675] Build status: STARTED
[04:46:07.675] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:47:0) after 9m29s
[04:55:36.674] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:47:0)
[04:55:36.690] Build status: STARTED
[04:55:36.690] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:48:0) after 5m50s
[05:01:26.756] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:48:0)
[05:01:26.775] Build status: STARTED
[05:01:26.775] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:49:0) after 1m22s
[05:02:48.845] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:49:0)
[05:02:48.866] Build status: STARTED
[05:02:48.866] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:50:0) after 3m30s
[05:06:19.169] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:50:0)
[05:06:19.190] Build status: STARTED
[05:06:19.190] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8840957230733048016:51:0) after 9m21s
[05:06:28.489] Received PubSub notification, asking Buildbucket for the build status
[05:06:28.517] Build:
{
"id": "8675527168743519793",
"builder": {
"project": "chromium-m151",
"bucket": "ci",
"builder": "win-official"
},
"number": 152,
"createdBy": "project:chromium-m151",
"createTime": "2026-07-23T01:19:40.092779474Z",
"startTime": "2026-07-23T01:19:50.952453905Z",
"endTime": "2026-07-23T05:06:28.133140794Z",
"updateTime": "2026-07-23T05:06:28.133140794Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "34c12a9053938141656d340ede49b9fc573cc752",
"ref": "refs/branch-heads/7922"
}
}
}
[05:06:28.517] Invocation finished in 3h46m49.705977227s with status SUCCEEDED