[01:50:32.163] New invocation is queued and will start shortly
[01:50:33.232] Starting the invocation (attempt 1)
[01:50:33.260] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/21c11c6d380d6a80db7493679bdd9f2e7acac4bf"
[01:50:33.260] Popped gitiles commit info from properties and tags
[01:50:33.260] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:50:33.260] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:50:33.260] Buildbucket request:
{
"requestId": "8861973783562700448",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8861973783562700448",
"job": "chromium-m143/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cafe1c98bda523f1e44f2ead93bed0265dd8948f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@cafe1c98bda523f1e44f2ead93bed0265dd8948f",
"title": "cafe1c98bda523f1e44f2ead93bed0265dd8948f",
"url": "https://chromium.googlesource.com/chromium/src/+/cafe1c98bda523f1e44f2ead93bed0265dd8948f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "eea0156dae0ea58514001dc1bddd771e86c14af8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@eea0156dae0ea58514001dc1bddd771e86c14af8",
"title": "eea0156dae0ea58514001dc1bddd771e86c14af8",
"url": "https://chromium.googlesource.com/chromium/src/+/eea0156dae0ea58514001dc1bddd771e86c14af8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9ca75bcf924304b536a7bb95201077ba63e6d518"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@9ca75bcf924304b536a7bb95201077ba63e6d518",
"title": "9ca75bcf924304b536a7bb95201077ba63e6d518",
"url": "https://chromium.googlesource.com/chromium/src/+/9ca75bcf924304b536a7bb95201077ba63e6d518"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5611603f9d8718fd64137397b07afc89090e6a1b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@5611603f9d8718fd64137397b07afc89090e6a1b",
"title": "5611603f9d8718fd64137397b07afc89090e6a1b",
"url": "https://chromium.googlesource.com/chromium/src/+/5611603f9d8718fd64137397b07afc89090e6a1b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d1bf88b343729a9b4f8d9aeca00044985b35c2f2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@d1bf88b343729a9b4f8d9aeca00044985b35c2f2",
"title": "d1bf88b343729a9b4f8d9aeca00044985b35c2f2",
"url": "https://chromium.googlesource.com/chromium/src/+/d1bf88b343729a9b4f8d9aeca00044985b35c2f2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "139fe34db76938a04c6cf1b8404d67ba65e72039"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@139fe34db76938a04c6cf1b8404d67ba65e72039",
"title": "139fe34db76938a04c6cf1b8404d67ba65e72039",
"url": "https://chromium.googlesource.com/chromium/src/+/139fe34db76938a04c6cf1b8404d67ba65e72039"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "77abb575150e96fbe33e019ea88e974888b9ba7c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@77abb575150e96fbe33e019ea88e974888b9ba7c",
"title": "77abb575150e96fbe33e019ea88e974888b9ba7c",
"url": "https://chromium.googlesource.com/chromium/src/+/77abb575150e96fbe33e019ea88e974888b9ba7c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e7b9256138a4227e449d120eca5da813d0f6ed06"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@e7b9256138a4227e449d120eca5da813d0f6ed06",
"title": "e7b9256138a4227e449d120eca5da813d0f6ed06",
"url": "https://chromium.googlesource.com/chromium/src/+/e7b9256138a4227e449d120eca5da813d0f6ed06"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "94dd5bd4f992c5790d5aabf5ea592992ea08f6e8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@94dd5bd4f992c5790d5aabf5ea592992ea08f6e8",
"title": "94dd5bd4f992c5790d5aabf5ea592992ea08f6e8",
"url": "https://chromium.googlesource.com/chromium/src/+/94dd5bd4f992c5790d5aabf5ea592992ea08f6e8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "21c11c6d380d6a80db7493679bdd9f2e7acac4bf"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@21c11c6d380d6a80db7493679bdd9f2e7acac4bf",
"title": "21c11c6d380d6a80db7493679bdd9f2e7acac4bf",
"url": "https://chromium.googlesource.com/chromium/src/+/21c11c6d380d6a80db7493679bdd9f2e7acac4bf"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "21c11c6d380d6a80db7493679bdd9f2e7acac4bf",
"ref": "refs/branch-heads/7499"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8861973783562700448"
},
{
"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"
}
}
[01:50:33.705] Scheduled build:
{
"id": "8696543721704620257",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "win32-official"
},
"number": 230,
"createdBy": "project:chromium-m143",
"createTime": "2025-12-03T01:50:33.314559057Z",
"updateTime": "2025-12-03T01:50:33.314559057Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "21c11c6d380d6a80db7493679bdd9f2e7acac4bf",
"ref": "refs/branch-heads/7499"
}
}
}
[01:50:33.705] Task URL: https://cr-buildbucket.appspot.com/build/8696543721704620257
[01:50:33.705] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:2:0) after 1m38s
[01:50:34.671] Received PubSub notification, asking Buildbucket for the build status
[01:50:34.683] Build status: SCHEDULED
[01:51:59.437] Received PubSub notification, asking Buildbucket for the build status
[01:51:59.464] Build status: STARTED
[01:52:11.775] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:2:0)
[01:52:11.790] Build status: STARTED
[01:52:11.790] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:5:0) after 4m32s
[01:56:43.849] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:5:0)
[01:56:43.877] Build status: STARTED
[01:56:43.877] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:6:0) after 8m54s
[02:05:37.890] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:6:0)
[02:05:37.915] Build status: STARTED
[02:05:37.915] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:7:0) after 5m44s
[02:11:21.929] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:7:0)
[02:11:21.961] Build status: STARTED
[02:11:21.961] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:8:0) after 1m45s
[02:13:07.071] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:8:0)
[02:13:07.089] Build status: STARTED
[02:13:07.089] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:9:0) after 7m41s
[02:20:48.204] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:9:0)
[02:20:48.227] Build status: STARTED
[02:20:48.227] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:10:0) after 5m56s
[02:26:44.265] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:10:0)
[02:26:44.314] Build status: STARTED
[02:26:44.315] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:11:0) after 7m41s
[02:34:25.332] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:11:0)
[02:34:25.359] Build status: STARTED
[02:34:25.359] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:12:0) after 3m27s
[02:37:52.408] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:12:0)
[02:37:52.728] Build status: STARTED
[02:37:52.728] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:13:0) after 6m51s
[02:44:43.739] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:13:0)
[02:44:43.879] Build status: STARTED
[02:44:43.879] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:14:0) after 5m14s
[02:49:57.897] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:14:0)
[02:49:57.911] Build status: STARTED
[02:49:57.911] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:15:0) after 5m29s
[02:55:26.997] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:15:0)
[02:55:27.027] Build status: STARTED
[02:55:27.027] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:16:0) after 8m36s
[03:04:03.042] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:16:0)
[03:04:03.081] Build status: STARTED
[03:04:03.081] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:17:0) after 9m38s
[03:13:41.109] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:17:0)
[03:13:41.129] Build status: STARTED
[03:13:41.129] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:18:0) after 5m35s
[03:19:16.248] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:18:0)
[03:19:16.263] Build status: STARTED
[03:19:16.263] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:19:0) after 5m20s
[03:24:36.288] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:19:0)
[03:24:36.425] Build status: STARTED
[03:24:36.425] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:20:0) after 4m25s
[03:29:01.441] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:20:0)
[03:29:01.463] Build status: STARTED
[03:29:01.463] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:21:0) after 6m15s
[03:35:16.511] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:21:0)
[03:35:16.530] Build status: STARTED
[03:35:16.530] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:22:0) after 9m58s
[03:45:14.576] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:22:0)
[03:45:14.613] Build status: STARTED
[03:45:14.613] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:23:0) after 8m42s
[03:53:56.673] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:23:0)
[03:53:56.698] Build status: STARTED
[03:53:56.698] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:24:0) after 9m38s
[04:03:34.780] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:24:0)
[04:03:34.816] Build status: STARTED
[04:03:34.816] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:25:0) after 3m1s
[04:06:35.831] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:25:0)
[04:06:35.855] Build status: STARTED
[04:06:35.855] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:26:0) after 6m15s
[04:12:50.961] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:26:0)
[04:12:50.981] Build status: STARTED
[04:12:50.981] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:27:0) after 4m55s
[04:17:46.004] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:27:0)
[04:17:46.039] Build status: STARTED
[04:17:46.039] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:28:0) after 4m29s
[04:22:15.154] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:28:0)
[04:22:15.291] Build status: STARTED
[04:22:15.291] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:29:0) after 2m21s
[04:24:36.312] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:29:0)
[04:24:36.349] Build status: STARTED
[04:24:36.350] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:30:0) after 7m4s
[04:31:40.408] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:30:0)
[04:31:40.495] Build status: STARTED
[04:31:40.495] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:31:0) after 8m47s
[04:40:27.514] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:31:0)
[04:40:27.534] Build status: STARTED
[04:40:27.535] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:32:0) after 8m27s
[04:48:54.605] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:32:0)
[04:48:54.619] Build status: STARTED
[04:48:54.619] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:33:0) after 6m41s
[04:55:35.632] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:33:0)
[04:55:35.647] Build status: STARTED
[04:55:35.647] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:34:0) after 6m30s
[05:02:05.683] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:34:0)
[05:02:05.777] Build status: STARTED
[05:02:05.777] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:35:0) after 6m5s
[05:08:10.846] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:35:0)
[05:08:10.891] Build status: STARTED
[05:08:10.892] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:36:0) after 9m57s
[05:18:07.905] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:36:0)
[05:18:07.919] Build status: STARTED
[05:18:07.919] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:37:0) after 7m55s
[05:26:02.951] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:37:0)
[05:26:03.040] Build status: STARTED
[05:26:03.041] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861973783562700448:38:0) after 6m20s
[05:27:53.271] Received PubSub notification, asking Buildbucket for the build status
[05:27:53.288] Build:
{
"id": "8696543721704620257",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "win32-official"
},
"number": 230,
"createdBy": "project:chromium-m143",
"createTime": "2025-12-03T01:50:33.314559057Z",
"startTime": "2025-12-03T01:51:59.174736629Z",
"endTime": "2025-12-03T05:27:53.036554953Z",
"updateTime": "2025-12-03T05:27:53.036554953Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "21c11c6d380d6a80db7493679bdd9f2e7acac4bf",
"ref": "refs/branch-heads/7499"
}
}
}
[05:27:53.288] Invocation finished in 3h37m21.129957407s with status SUCCEEDED