[05:12:24.953] New invocation is queued and will start shortly
[05:12:26.021] Starting the invocation (attempt 1)
[05:12:26.049] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8b88707bbf8f047a52d7df9863e66850a5be6af4"
[05:12:26.049] Popped gitiles commit info from properties and tags
[05:12:26.049] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:12:26.049] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:12:26.049] Buildbucket request:
{
"requestId": "8843932286068862352",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8843932286068862352",
"job": "chromium-m149/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d6ff684cc547dc4317d73a7f8d66ad127ca5dbe3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@d6ff684cc547dc4317d73a7f8d66ad127ca5dbe3",
"title": "d6ff684cc547dc4317d73a7f8d66ad127ca5dbe3",
"url": "https://chromium.googlesource.com/chromium/src/+/d6ff684cc547dc4317d73a7f8d66ad127ca5dbe3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "744e963438c6a8beac94c710d69c7752935a7907"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@744e963438c6a8beac94c710d69c7752935a7907",
"title": "744e963438c6a8beac94c710d69c7752935a7907",
"url": "https://chromium.googlesource.com/chromium/src/+/744e963438c6a8beac94c710d69c7752935a7907"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "97f1efe97175e7580ba714d2b93daac808c34312"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@97f1efe97175e7580ba714d2b93daac808c34312",
"title": "97f1efe97175e7580ba714d2b93daac808c34312",
"url": "https://chromium.googlesource.com/chromium/src/+/97f1efe97175e7580ba714d2b93daac808c34312"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a5c750ae8f8fdfe320662d31756d3cfa37d0ecb8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@a5c750ae8f8fdfe320662d31756d3cfa37d0ecb8",
"title": "a5c750ae8f8fdfe320662d31756d3cfa37d0ecb8",
"url": "https://chromium.googlesource.com/chromium/src/+/a5c750ae8f8fdfe320662d31756d3cfa37d0ecb8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "56b9af13fd51dc1831bd42999e5c59d261113e30"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@56b9af13fd51dc1831bd42999e5c59d261113e30",
"title": "56b9af13fd51dc1831bd42999e5c59d261113e30",
"url": "https://chromium.googlesource.com/chromium/src/+/56b9af13fd51dc1831bd42999e5c59d261113e30"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "64ebe857af10a859e4629c3dd6c11fc16a25ea25"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@64ebe857af10a859e4629c3dd6c11fc16a25ea25",
"title": "64ebe857af10a859e4629c3dd6c11fc16a25ea25",
"url": "https://chromium.googlesource.com/chromium/src/+/64ebe857af10a859e4629c3dd6c11fc16a25ea25"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9730a7f562b37cb5d7edb553ca6a6660c7790e29"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@9730a7f562b37cb5d7edb553ca6a6660c7790e29",
"title": "9730a7f562b37cb5d7edb553ca6a6660c7790e29",
"url": "https://chromium.googlesource.com/chromium/src/+/9730a7f562b37cb5d7edb553ca6a6660c7790e29"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e3bf3aa296adaa83141d8850a033244c22d2b61b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@e3bf3aa296adaa83141d8850a033244c22d2b61b",
"title": "e3bf3aa296adaa83141d8850a033244c22d2b61b",
"url": "https://chromium.googlesource.com/chromium/src/+/e3bf3aa296adaa83141d8850a033244c22d2b61b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0e8ec63be7d545b9cb305fc650e724ab632e5a56"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@0e8ec63be7d545b9cb305fc650e724ab632e5a56",
"title": "0e8ec63be7d545b9cb305fc650e724ab632e5a56",
"url": "https://chromium.googlesource.com/chromium/src/+/0e8ec63be7d545b9cb305fc650e724ab632e5a56"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fae36ac3f83a3b32fd680c8ad29d7e3bd11e5feb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@fae36ac3f83a3b32fd680c8ad29d7e3bd11e5feb",
"title": "fae36ac3f83a3b32fd680c8ad29d7e3bd11e5feb",
"url": "https://chromium.googlesource.com/chromium/src/+/fae36ac3f83a3b32fd680c8ad29d7e3bd11e5feb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "275bcf5a749a64f3eb2aa234bc9a2796e7425498"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@275bcf5a749a64f3eb2aa234bc9a2796e7425498",
"title": "275bcf5a749a64f3eb2aa234bc9a2796e7425498",
"url": "https://chromium.googlesource.com/chromium/src/+/275bcf5a749a64f3eb2aa234bc9a2796e7425498"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8b88707bbf8f047a52d7df9863e66850a5be6af4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@8b88707bbf8f047a52d7df9863e66850a5be6af4",
"title": "8b88707bbf8f047a52d7df9863e66850a5be6af4",
"url": "https://chromium.googlesource.com/chromium/src/+/8b88707bbf8f047a52d7df9863e66850a5be6af4"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8b88707bbf8f047a52d7df9863e66850a5be6af4",
"ref": "refs/branch-heads/7827"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8843932286068862352"
},
{
"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"
}
}
[05:12:26.564] Scheduled build:
{
"id": "8678502224192019921",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win32-official"
},
"number": 247,
"createdBy": "project:chromium-m149",
"createTime": "2026-06-20T05:12:26.121522390Z",
"updateTime": "2026-06-20T05:12:26.121522390Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8b88707bbf8f047a52d7df9863e66850a5be6af4",
"ref": "refs/branch-heads/7827"
}
}
}
[05:12:26.564] Task URL: https://cr-buildbucket.appspot.com/build/8678502224192019921
[05:12:26.564] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:2:0) after 5m53s
[05:12:27.571] Received PubSub notification, asking Buildbucket for the build status
[05:12:27.759] Build status: SCHEDULED
[05:12:31.228] Received PubSub notification, asking Buildbucket for the build status
[05:12:31.256] Build status: STARTED
[05:18:19.589] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:2:0)
[05:18:19.607] Build status: STARTED
[05:18:19.607] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:5:0) after 4m8s
[05:22:27.690] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:5:0)
[05:22:27.709] Build status: STARTED
[05:22:27.709] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:6:0) after 9m50s
[05:32:17.876] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:6:0)
[05:32:17.889] Build status: STARTED
[05:32:17.889] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:7:0) after 1m30s
[05:33:47.893] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:7:0)
[05:33:47.920] Build status: STARTED
[05:33:47.920] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:8:0) after 7m28s
[05:41:16.181] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:8:0)
[05:41:16.197] Build status: STARTED
[05:41:16.197] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:9:0) after 7m16s
[05:48:32.247] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:9:0)
[05:48:32.271] Build status: STARTED
[05:48:32.271] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:10:0) after 2m28s
[05:51:00.284] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:10:0)
[05:51:00.304] Build status: STARTED
[05:51:00.304] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:11:0) after 4m6s
[05:55:06.310] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:11:0)
[05:55:06.356] Build status: STARTED
[05:55:06.356] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:12:0) after 6m36s
[06:01:42.529] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:12:0)
[06:01:42.567] Build status: STARTED
[06:01:42.567] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:13:0) after 5m19s
[06:07:01.582] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:13:0)
[06:07:01.603] Build status: STARTED
[06:07:01.603] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:14:0) after 3m39s
[06:10:40.856] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:14:0)
[06:10:40.883] Build status: STARTED
[06:10:40.883] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:15:0) after 8m57s
[06:19:38.041] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:15:0)
[06:19:38.059] Build status: STARTED
[06:19:38.059] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:16:0) after 4m40s
[06:24:18.311] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:16:0)
[06:24:18.340] Build status: STARTED
[06:24:18.340] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:17:0) after 7m45s
[06:32:03.352] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:17:0)
[06:32:03.371] Build status: STARTED
[06:32:03.371] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:18:0) after 5m3s
[06:37:06.393] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:18:0)
[06:37:06.413] Build status: STARTED
[06:37:06.413] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:19:0) after 3m39s
[06:40:45.445] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:19:0)
[06:40:45.480] Build status: STARTED
[06:40:45.480] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:20:0) after 9m41s
[06:50:26.544] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:20:0)
[06:50:26.565] Build status: STARTED
[06:50:26.565] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:21:0) after 2m4s
[06:52:30.624] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:21:0)
[06:52:30.709] Build status: STARTED
[06:52:30.709] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:22:0) after 1m20s
[06:53:50.741] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:22:0)
[06:53:50.762] Build status: STARTED
[06:53:50.763] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:23:0) after 4m40s
[06:58:30.829] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:23:0)
[06:58:30.846] Build status: STARTED
[06:58:30.846] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:24:0) after 8m27s
[07:06:57.895] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:24:0)
[07:06:57.910] Build status: STARTED
[07:06:57.910] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:25:0) after 5m20s
[07:12:18.647] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:25:0)
[07:12:18.683] Build status: STARTED
[07:12:18.683] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:26:0) after 6m23s
[07:18:42.047] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:26:0)
[07:18:42.078] Build status: STARTED
[07:18:42.078] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:27:0) after 5m7s
[07:23:49.348] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:27:0)
[07:23:49.423] Build status: STARTED
[07:23:49.423] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:28:0) after 3m43s
[07:27:32.813] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:28:0)
[07:27:32.841] Build status: STARTED
[07:27:32.842] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:29:0) after 7m14s
[07:34:46.834] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:29:0)
[07:34:46.853] Build status: STARTED
[07:34:46.853] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:30:0) after 7m18s
[07:42:04.901] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:30:0)
[07:42:04.926] Build status: STARTED
[07:42:04.926] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:31:0) after 1m7s
[07:43:12.380] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:31:0)
[07:43:12.444] Build status: STARTED
[07:43:12.444] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:32:0) after 9m33s
[07:52:45.463] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:32:0)
[07:52:45.494] Build status: STARTED
[07:52:45.494] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:33:0) after 1m59s
[07:54:44.500] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:33:0)
[07:54:44.521] Build status: STARTED
[07:54:44.521] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:34:0) after 9m11s
[08:03:55.534] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:34:0)
[08:03:55.555] Build status: STARTED
[08:03:55.555] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:35:0) after 8m40s
[08:12:35.558] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:35:0)
[08:12:35.587] Build status: STARTED
[08:12:35.587] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:36:0) after 9m57s
[08:22:32.998] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:36:0)
[08:22:33.018] Build status: STARTED
[08:22:33.018] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:37:0) after 7m23s
[08:29:56.163] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:37:0)
[08:29:56.182] Build status: STARTED
[08:29:56.182] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:38:0) after 9m54s
[08:39:50.200] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:38:0)
[08:39:50.219] Build status: STARTED
[08:39:50.219] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:39:0) after 7m16s
[08:47:06.667] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:39:0)
[08:47:06.691] Build status: STARTED
[08:47:06.691] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:40:0) after 9m19s
[08:56:25.772] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:40:0)
[08:56:25.798] Build status: STARTED
[08:56:25.798] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:41:0) after 7m48s
[09:04:13.784] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:41:0)
[09:04:13.851] Build status: STARTED
[09:04:13.851] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:42:0) after 9m17s
[09:13:31.006] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:42:0)
[09:13:31.042] Build status: STARTED
[09:13:31.042] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:43:0) after 7m39s
[09:21:10.059] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:43:0)
[09:21:10.079] Build status: STARTED
[09:21:10.079] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:44:0) after 7m46s
[09:28:56.095] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:44:0)
[09:28:56.114] Build status: STARTED
[09:28:56.114] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:45:0) after 1m46s
[09:30:42.135] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:45:0)
[09:30:42.156] Build status: STARTED
[09:30:42.156] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:46:0) after 9m40s
[09:40:22.233] Handling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:46:0)
[09:40:22.249] Build status: STARTED
[09:40:22.249] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win32-official:8843932286068862352:47:0) after 3m38s
[09:41:13.728] Received PubSub notification, asking Buildbucket for the build status
[09:41:13.761] Build:
{
"id": "8678502224192019921",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win32-official"
},
"number": 247,
"createdBy": "project:chromium-m149",
"createTime": "2026-06-20T05:12:26.121522390Z",
"startTime": "2026-06-20T05:12:30.799676874Z",
"endTime": "2026-06-20T09:41:10.043207938Z",
"updateTime": "2026-06-20T09:41:10.043207938Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8b88707bbf8f047a52d7df9863e66850a5be6af4",
"ref": "refs/branch-heads/7827"
}
}
}
[09:41:13.761] Invocation finished in 4h28m48.812162727s with status SUCCEEDED