[10:11:25.237] New invocation is queued and will start shortly
[10:11:26.540] Starting the invocation (attempt 1)
[10:11:26.568] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/399db69655b63a6c5fa3c37ed7dfc1bbe4269138"
[10:11:26.568] Popped gitiles commit info from properties and tags
[10:11:26.568] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:11:26.568] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:11:26.569] Buildbucket request:
{
"requestId": "8864841373564289696",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8864841373564289696",
"job": "chromium-m143/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fd42cdad312064e7c93c4160c050d58628a0a9ac"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@fd42cdad312064e7c93c4160c050d58628a0a9ac",
"title": "fd42cdad312064e7c93c4160c050d58628a0a9ac",
"url": "https://chromium.googlesource.com/chromium/src/+/fd42cdad312064e7c93c4160c050d58628a0a9ac"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "97a32ec527cd96c3235f5db58f9882de3d08d731"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@97a32ec527cd96c3235f5db58f9882de3d08d731",
"title": "97a32ec527cd96c3235f5db58f9882de3d08d731",
"url": "https://chromium.googlesource.com/chromium/src/+/97a32ec527cd96c3235f5db58f9882de3d08d731"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "daf66d82df68918934108884b93244f8a00e3dcc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@daf66d82df68918934108884b93244f8a00e3dcc",
"title": "daf66d82df68918934108884b93244f8a00e3dcc",
"url": "https://chromium.googlesource.com/chromium/src/+/daf66d82df68918934108884b93244f8a00e3dcc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "92b1628ef332683fccae81d5a881ffdd569717b9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@92b1628ef332683fccae81d5a881ffdd569717b9",
"title": "92b1628ef332683fccae81d5a881ffdd569717b9",
"url": "https://chromium.googlesource.com/chromium/src/+/92b1628ef332683fccae81d5a881ffdd569717b9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1630f3b59d45b15438f6a33d387e71a7bb52cdae"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@1630f3b59d45b15438f6a33d387e71a7bb52cdae",
"title": "1630f3b59d45b15438f6a33d387e71a7bb52cdae",
"url": "https://chromium.googlesource.com/chromium/src/+/1630f3b59d45b15438f6a33d387e71a7bb52cdae"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2cd487860a921247d1545cee34c880af123ac39d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@2cd487860a921247d1545cee34c880af123ac39d",
"title": "2cd487860a921247d1545cee34c880af123ac39d",
"url": "https://chromium.googlesource.com/chromium/src/+/2cd487860a921247d1545cee34c880af123ac39d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0c6f53f536a0ddd3d28769dd0fc15007cbc3dedc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@0c6f53f536a0ddd3d28769dd0fc15007cbc3dedc",
"title": "0c6f53f536a0ddd3d28769dd0fc15007cbc3dedc",
"url": "https://chromium.googlesource.com/chromium/src/+/0c6f53f536a0ddd3d28769dd0fc15007cbc3dedc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fe922f274a1166d9079cf4261a7993a9c1eda050"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@fe922f274a1166d9079cf4261a7993a9c1eda050",
"title": "fe922f274a1166d9079cf4261a7993a9c1eda050",
"url": "https://chromium.googlesource.com/chromium/src/+/fe922f274a1166d9079cf4261a7993a9c1eda050"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8e7ba2c7a017f14004f265199027fb7915c6c0e5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@8e7ba2c7a017f14004f265199027fb7915c6c0e5",
"title": "8e7ba2c7a017f14004f265199027fb7915c6c0e5",
"url": "https://chromium.googlesource.com/chromium/src/+/8e7ba2c7a017f14004f265199027fb7915c6c0e5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "efcdf26afec9afc27d2156c40c45d867ae66b6fc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@efcdf26afec9afc27d2156c40c45d867ae66b6fc",
"title": "efcdf26afec9afc27d2156c40c45d867ae66b6fc",
"url": "https://chromium.googlesource.com/chromium/src/+/efcdf26afec9afc27d2156c40c45d867ae66b6fc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "544a60261d6314e63dbe97e913af3b5d0f5d9e5c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@544a60261d6314e63dbe97e913af3b5d0f5d9e5c",
"title": "544a60261d6314e63dbe97e913af3b5d0f5d9e5c",
"url": "https://chromium.googlesource.com/chromium/src/+/544a60261d6314e63dbe97e913af3b5d0f5d9e5c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "399db69655b63a6c5fa3c37ed7dfc1bbe4269138"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@399db69655b63a6c5fa3c37ed7dfc1bbe4269138",
"title": "399db69655b63a6c5fa3c37ed7dfc1bbe4269138",
"url": "https://chromium.googlesource.com/chromium/src/+/399db69655b63a6c5fa3c37ed7dfc1bbe4269138"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "399db69655b63a6c5fa3c37ed7dfc1bbe4269138",
"ref": "refs/branch-heads/7499"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8864841373564289696"
},
{
"key": "scheduler_job_id",
"value": "chromium-m143/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[10:11:27.374] Scheduled build:
{
"id": "8699411311456015265",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "win32-official"
},
"number": 24,
"createdBy": "project:chromium-m143",
"createTime": "2025-11-01T10:11:26.618961691Z",
"updateTime": "2025-11-01T10:11:26.618961691Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "399db69655b63a6c5fa3c37ed7dfc1bbe4269138",
"ref": "refs/branch-heads/7499"
}
}
}
[10:11:27.374] Task URL: https://cr-buildbucket.appspot.com/build/8699411311456015265
[10:11:27.374] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:2:0) after 4m28s
[10:11:28.051] Received PubSub notification, asking Buildbucket for the build status
[10:11:28.189] Build status: SCHEDULED
[10:11:32.714] Received PubSub notification, asking Buildbucket for the build status
[10:11:32.753] Build status: STARTED
[10:15:55.343] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:2:0)
[10:15:55.362] Build status: STARTED
[10:15:55.362] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:5:0) after 8m19s
[10:24:14.481] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:5:0)
[10:24:14.497] Build status: STARTED
[10:24:14.497] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:6:0) after 6m50s
[10:31:04.522] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:6:0)
[10:31:04.553] Build status: STARTED
[10:31:04.553] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:7:0) after 7m43s
[10:38:47.659] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:7:0)
[10:38:47.676] Build status: STARTED
[10:38:47.676] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:8:0) after 7m42s
[10:46:29.692] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:8:0)
[10:46:29.712] Build status: STARTED
[10:46:29.713] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:9:0) after 2m28s
[10:48:57.875] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:9:0)
[10:48:57.895] Build status: STARTED
[10:48:57.895] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:10:0) after 6m18s
[10:55:15.911] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:10:0)
[10:55:15.926] Build status: STARTED
[10:55:15.926] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:11:0) after 8m32s
[11:03:47.961] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:11:0)
[11:03:48.017] Build status: STARTED
[11:03:48.017] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:12:0) after 3m16s
[11:07:04.147] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:12:0)
[11:07:04.166] Build status: STARTED
[11:07:04.166] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:13:0) after 2m43s
[11:09:47.224] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:13:0)
[11:09:47.299] Build status: STARTED
[11:09:47.299] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:14:0) after 4m6s
[11:13:53.319] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:14:0)
[11:13:53.333] Build status: STARTED
[11:13:53.333] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:15:0) after 4m42s
[11:18:35.462] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:15:0)
[11:18:35.478] Build status: STARTED
[11:18:35.478] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:16:0) after 5m59s
[11:24:34.488] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:16:0)
[11:24:34.566] Build status: STARTED
[11:24:34.566] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:17:0) after 4m8s
[11:28:42.584] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:17:0)
[11:28:42.605] Build status: STARTED
[11:28:42.606] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:18:0) after 2m31s
[11:31:13.737] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:18:0)
[11:31:13.757] Build status: STARTED
[11:31:13.757] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:19:0) after 7m32s
[11:38:46.035] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:19:0)
[11:38:46.081] Build status: STARTED
[11:38:46.081] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:20:0) after 2m14s
[11:41:00.151] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:20:0)
[11:41:00.170] Build status: STARTED
[11:41:00.170] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:21:0) after 1m46s
[11:42:46.275] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:21:0)
[11:42:46.304] Build status: STARTED
[11:42:46.304] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:22:0) after 7m17s
[11:50:03.923] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:22:0)
[11:50:03.940] Build status: STARTED
[11:50:03.940] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:23:0) after 9m48s
[11:59:51.955] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:23:0)
[11:59:51.975] Build status: STARTED
[11:59:51.975] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:24:0) after 2m52s
[12:02:44.090] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:24:0)
[12:02:44.110] Build status: STARTED
[12:02:44.110] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:25:0) after 4m39s
[12:07:23.127] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:25:0)
[12:07:23.151] Build status: STARTED
[12:07:23.152] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:26:0) after 7m37s
[12:15:00.227] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:26:0)
[12:15:00.244] Build status: STARTED
[12:15:00.244] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:27:0) after 7m12s
[12:22:12.263] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:27:0)
[12:22:12.280] Build status: STARTED
[12:22:12.280] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:28:0) after 5m29s
[12:27:41.421] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:28:0)
[12:27:41.461] Build status: STARTED
[12:27:41.461] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:29:0) after 3m14s
[12:30:55.479] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:29:0)
[12:30:55.512] Build status: STARTED
[12:30:55.512] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:30:0) after 5m9s
[12:36:04.635] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:30:0)
[12:36:04.650] Build status: STARTED
[12:36:04.650] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:31:0) after 5m32s
[12:41:36.817] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:31:0)
[12:41:36.838] Build status: STARTED
[12:41:36.838] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:32:0) after 9m51s
[12:51:27.862] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:32:0)
[12:51:27.880] Build status: STARTED
[12:51:27.880] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:33:0) after 2m43s
[12:54:10.894] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:33:0)
[12:54:10.907] Build status: STARTED
[12:54:10.907] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:34:0) after 8m35s
[13:02:46.205] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:34:0)
[13:02:46.221] Build status: STARTED
[13:02:46.221] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:35:0) after 3m42s
[13:06:28.509] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:35:0)
[13:06:28.561] Build status: STARTED
[13:06:28.561] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:36:0) after 7m21s
[13:13:49.576] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:36:0)
[13:13:49.654] Build status: STARTED
[13:13:49.654] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:37:0) after 6m42s
[13:20:31.659] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:37:0)
[13:20:31.673] Build status: STARTED
[13:20:31.673] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:38:0) after 6m52s
[13:27:23.896] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:38:0)
[13:27:23.909] Build status: STARTED
[13:27:23.909] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:39:0) after 7m58s
[13:35:21.988] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:39:0)
[13:35:22.001] Build status: STARTED
[13:35:22.001] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:40:0) after 2m11s
[13:37:33.016] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:40:0)
[13:37:33.030] Build status: STARTED
[13:37:33.030] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8864841373564289696:41:0) after 9m28s
[13:45:25.087] Received PubSub notification, asking Buildbucket for the build status
[13:45:25.106] Build:
{
"id": "8699411311456015265",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "win32-official"
},
"number": 24,
"createdBy": "project:chromium-m143",
"createTime": "2025-11-01T10:11:26.618961691Z",
"startTime": "2025-11-01T10:11:31.025885495Z",
"endTime": "2025-11-01T13:45:13.024297789Z",
"updateTime": "2025-11-01T13:45:13.024297789Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "399db69655b63a6c5fa3c37ed7dfc1bbe4269138",
"ref": "refs/branch-heads/7499"
}
}
}
[13:45:25.106] Invocation finished in 3h33m59.88249346s with status SUCCEEDED