[05:17:16.623] New invocation is queued and will start shortly
[05:17:18.228] Starting the invocation (attempt 1)
[05:17:18.262] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/523c0bac305df4f34c0fb4ec7b4a46c5f5278b16"
[05:17:18.262] Popped gitiles commit info from properties and tags
[05:17:18.262] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:17:18.263] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:17:18.263] Buildbucket request:
{
"requestId": "8852810482937293840",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8852810482937293840",
"job": "chromium-m146/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "00b6c4d0646cb2becac82f0100bfdc26dbeaad4b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@00b6c4d0646cb2becac82f0100bfdc26dbeaad4b",
"title": "00b6c4d0646cb2becac82f0100bfdc26dbeaad4b",
"url": "https://chromium.googlesource.com/chromium/src/+/00b6c4d0646cb2becac82f0100bfdc26dbeaad4b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1b7b7712a7a90f832af330d4fb2fdcb33e76fdeb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@1b7b7712a7a90f832af330d4fb2fdcb33e76fdeb",
"title": "1b7b7712a7a90f832af330d4fb2fdcb33e76fdeb",
"url": "https://chromium.googlesource.com/chromium/src/+/1b7b7712a7a90f832af330d4fb2fdcb33e76fdeb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "623f811ab44af3426f998f0bdb78ade4add9c12b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@623f811ab44af3426f998f0bdb78ade4add9c12b",
"title": "623f811ab44af3426f998f0bdb78ade4add9c12b",
"url": "https://chromium.googlesource.com/chromium/src/+/623f811ab44af3426f998f0bdb78ade4add9c12b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4eda39a96e37d826ec09fc16fe82ae1fa636cda0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@4eda39a96e37d826ec09fc16fe82ae1fa636cda0",
"title": "4eda39a96e37d826ec09fc16fe82ae1fa636cda0",
"url": "https://chromium.googlesource.com/chromium/src/+/4eda39a96e37d826ec09fc16fe82ae1fa636cda0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d99716472e875710135098208ebd5ab053556e21"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@d99716472e875710135098208ebd5ab053556e21",
"title": "d99716472e875710135098208ebd5ab053556e21",
"url": "https://chromium.googlesource.com/chromium/src/+/d99716472e875710135098208ebd5ab053556e21"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4cce8b031a260c65e45b4d62cbde1561767e0416"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@4cce8b031a260c65e45b4d62cbde1561767e0416",
"title": "4cce8b031a260c65e45b4d62cbde1561767e0416",
"url": "https://chromium.googlesource.com/chromium/src/+/4cce8b031a260c65e45b4d62cbde1561767e0416"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f65e938d177a685bd063b5a6aff60345b5773e71"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@f65e938d177a685bd063b5a6aff60345b5773e71",
"title": "f65e938d177a685bd063b5a6aff60345b5773e71",
"url": "https://chromium.googlesource.com/chromium/src/+/f65e938d177a685bd063b5a6aff60345b5773e71"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "523c0bac305df4f34c0fb4ec7b4a46c5f5278b16"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@523c0bac305df4f34c0fb4ec7b4a46c5f5278b16",
"title": "523c0bac305df4f34c0fb4ec7b4a46c5f5278b16",
"url": "https://chromium.googlesource.com/chromium/src/+/523c0bac305df4f34c0fb4ec7b4a46c5f5278b16"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "523c0bac305df4f34c0fb4ec7b4a46c5f5278b16",
"ref": "refs/branch-heads/7680"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8852810482937293840"
},
{
"key": "scheduler_job_id",
"value": "chromium-m146/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:17:18.761] Scheduled build:
{
"id": "8687380420505032145",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "win32-official"
},
"number": 181,
"createdBy": "project:chromium-m146",
"createTime": "2026-03-14T05:17:18.321823372Z",
"updateTime": "2026-03-14T05:17:18.321823372Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "523c0bac305df4f34c0fb4ec7b4a46c5f5278b16",
"ref": "refs/branch-heads/7680"
}
}
}
[05:17:18.761] Task URL: https://cr-buildbucket.appspot.com/build/8687380420505032145
[05:17:18.761] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:2:0) after 8m7s
[05:17:18.930] Received PubSub notification, asking Buildbucket for the build status
[05:17:18.973] Build status: SCHEDULED
[05:18:45.820] Received PubSub notification, asking Buildbucket for the build status
[05:18:45.843] Build status: STARTED
[05:25:25.928] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:2:0)
[05:25:25.952] Build status: STARTED
[05:25:25.952] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:5:0) after 4m35s
[05:30:00.968] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:5:0)
[05:30:01.024] Build status: STARTED
[05:30:01.024] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:6:0) after 4m8s
[05:34:09.340] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:6:0)
[05:34:09.360] Build status: STARTED
[05:34:09.360] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:7:0) after 5m58s
[05:40:07.524] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:7:0)
[05:40:07.544] Build status: STARTED
[05:40:07.544] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:8:0) after 7m48s
[05:47:55.559] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:8:0)
[05:47:55.576] Build status: STARTED
[05:47:55.576] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:9:0) after 2m42s
[05:50:38.033] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:9:0)
[05:50:38.059] Build status: STARTED
[05:50:38.059] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:10:0) after 6m31s
[05:57:09.613] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:10:0)
[05:57:09.633] Build status: STARTED
[05:57:09.633] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:11:0) after 8m13s
[06:05:22.664] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:11:0)
[06:05:22.717] Build status: STARTED
[06:05:22.718] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:12:0) after 2m16s
[06:07:38.732] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:12:0)
[06:07:38.764] Build status: STARTED
[06:07:38.764] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:13:0) after 3m57s
[06:11:35.791] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:13:0)
[06:11:35.879] Build status: STARTED
[06:11:35.879] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:14:0) after 7m8s
[06:18:44.010] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:14:0)
[06:18:44.023] Build status: STARTED
[06:18:44.023] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:15:0) after 7m48s
[06:26:32.040] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:15:0)
[06:26:32.068] Build status: STARTED
[06:26:32.068] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:16:0) after 4m22s
[06:30:54.085] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:16:0)
[06:30:54.170] Build status: STARTED
[06:30:54.170] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:17:0) after 8m2s
[06:38:56.337] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:17:0)
[06:38:56.419] Build status: STARTED
[06:38:56.419] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:18:0) after 5m0s
[06:43:56.438] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:18:0)
[06:43:56.475] Build status: STARTED
[06:43:56.475] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:19:0) after 5m5s
[06:49:01.594] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:19:0)
[06:49:01.615] Build status: STARTED
[06:49:01.615] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:20:0) after 7m29s
[06:56:30.748] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:20:0)
[06:56:30.786] Build status: STARTED
[06:56:30.786] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:21:0) after 5m2s
[07:01:32.802] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:21:0)
[07:01:32.819] Build status: STARTED
[07:01:32.819] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:22:0) after 8m24s
[07:09:56.793] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:22:0)
[07:09:56.819] Build status: STARTED
[07:09:56.819] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:23:0) after 3m6s
[07:13:02.852] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:23:0)
[07:13:02.935] Build status: STARTED
[07:13:02.936] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:24:0) after 8m7s
[07:21:50.511] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:24:0)
[07:21:50.684] Build status: STARTED
[07:21:50.684] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:25:0) after 3m22s
[07:25:13.389] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:25:0)
[07:25:13.475] Build status: STARTED
[07:25:13.475] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:26:0) after 2m24s
[07:27:37.692] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:26:0)
[07:27:37.728] Build status: STARTED
[07:27:37.728] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:27:0) after 5m55s
[07:33:32.998] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:27:0)
[07:33:33.050] Build status: STARTED
[07:33:33.050] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:28:0) after 1m55s
[07:35:28.217] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:28:0)
[07:35:28.238] Build status: STARTED
[07:35:28.238] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:29:0) after 9m45s
[07:45:14.192] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:29:0)
[07:45:14.222] Build status: STARTED
[07:45:14.222] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:30:0) after 5m3s
[07:50:17.239] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:30:0)
[07:50:17.260] Build status: STARTED
[07:50:17.260] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:31:0) after 1m23s
[07:51:40.396] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:31:0)
[07:51:40.417] Build status: STARTED
[07:51:40.417] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:32:0) after 3m22s
[07:55:02.652] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:32:0)
[07:55:02.669] Build status: STARTED
[07:55:02.669] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:33:0) after 4m15s
[07:59:17.810] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:33:0)
[07:59:17.825] Build status: STARTED
[07:59:17.825] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:34:0) after 7m24s
[08:06:41.840] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:34:0)
[08:06:41.858] Build status: STARTED
[08:06:41.858] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:35:0) after 4m18s
[08:11:00.103] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:35:0)
[08:11:00.129] Build status: STARTED
[08:11:00.129] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:36:0) after 1m38s
[08:12:38.378] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:36:0)
[08:12:38.425] Build status: STARTED
[08:12:38.425] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:37:0) after 7m25s
[08:20:03.449] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:37:0)
[08:20:03.465] Build status: STARTED
[08:20:03.465] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:38:0) after 1m17s
[08:21:20.569] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:38:0)
[08:21:20.601] Build status: STARTED
[08:21:20.601] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:39:0) after 3m26s
[08:24:46.632] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:39:0)
[08:24:46.657] Build status: STARTED
[08:24:46.657] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:40:0) after 7m12s
[08:31:58.672] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:40:0)
[08:31:58.696] Build status: STARTED
[08:31:58.696] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:41:0) after 1m35s
[08:33:33.812] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:41:0)
[08:33:33.833] Build status: STARTED
[08:33:33.833] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:42:0) after 6m2s
[08:39:35.849] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:42:0)
[08:39:35.864] Build status: STARTED
[08:39:35.864] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:43:0) after 5m53s
[08:45:28.932] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:43:0)
[08:45:28.957] Build status: STARTED
[08:45:28.957] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:44:0) after 8m40s
[08:54:09.023] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:44:0)
[08:54:09.054] Build status: STARTED
[08:54:09.054] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:45:0) after 5m18s
[08:59:27.071] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:45:0)
[08:59:27.096] Build status: STARTED
[08:59:27.096] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:46:0) after 7m24s
[09:06:51.887] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:46:0)
[09:06:51.913] Build status: STARTED
[09:06:51.913] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:47:0) after 5m45s
[09:12:36.957] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:47:0)
[09:12:37.006] Build status: STARTED
[09:12:37.006] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:48:0) after 6m48s
[09:19:25.023] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:48:0)
[09:19:25.042] Build status: STARTED
[09:19:25.042] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:49:0) after 5m53s
[09:25:18.087] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:49:0)
[09:25:18.104] Build status: STARTED
[09:25:18.104] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:50:0) after 5m25s
[09:30:43.120] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:50:0)
[09:30:43.135] Build status: STARTED
[09:30:43.135] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:51:0) after 1m10s
[09:31:53.603] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:51:0)
[09:31:53.622] Build status: STARTED
[09:31:53.622] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:52:0) after 1m43s
[09:33:37.183] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:52:0)
[09:33:37.214] Build status: STARTED
[09:33:37.215] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8852810482937293840:53:0) after 8m37s
[09:37:46.398] Received PubSub notification, asking Buildbucket for the build status
[09:37:46.536] Build:
{
"id": "8687380420505032145",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "win32-official"
},
"number": 181,
"createdBy": "project:chromium-m146",
"createTime": "2026-03-14T05:17:18.321823372Z",
"startTime": "2026-03-14T05:18:45.656270417Z",
"endTime": "2026-03-14T09:37:32.864456651Z",
"updateTime": "2026-03-14T09:37:32.864456651Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "523c0bac305df4f34c0fb4ec7b4a46c5f5278b16",
"ref": "refs/branch-heads/7680"
}
}
}
[09:37:46.536] Invocation finished in 4h20m29.917812052s with status SUCCEEDED