[19:27:50.110] New invocation is queued and will start shortly
[19:27:52.174] Starting the invocation (attempt 1)
[19:27:52.201] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cfb8ac1c0a31a1432636ac14a76a368ba1441497"
[19:27:52.201] Popped gitiles commit info from properties and tags
[19:27:52.201] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:27:52.201] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:27:52.202] Buildbucket request:
{
"requestId": "8868158454745307552",
"builder": {
"project": "chromium-m141",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8868158454745307552",
"job": "chromium-m141/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "948dc784999f6a9590e8ca106200f55513f16dc8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@948dc784999f6a9590e8ca106200f55513f16dc8",
"title": "948dc784999f6a9590e8ca106200f55513f16dc8",
"url": "https://chromium.googlesource.com/chromium/src/+/948dc784999f6a9590e8ca106200f55513f16dc8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "87e288c540cedbd67e96ef4db33912872c23ab84"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@87e288c540cedbd67e96ef4db33912872c23ab84",
"title": "87e288c540cedbd67e96ef4db33912872c23ab84",
"url": "https://chromium.googlesource.com/chromium/src/+/87e288c540cedbd67e96ef4db33912872c23ab84"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d837b65f7ead95d60d2f0f1185b0e6aa90e87a7b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@d837b65f7ead95d60d2f0f1185b0e6aa90e87a7b",
"title": "d837b65f7ead95d60d2f0f1185b0e6aa90e87a7b",
"url": "https://chromium.googlesource.com/chromium/src/+/d837b65f7ead95d60d2f0f1185b0e6aa90e87a7b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fbed9fcd80cd6dbbd688dfe1849963f642222b22"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@fbed9fcd80cd6dbbd688dfe1849963f642222b22",
"title": "fbed9fcd80cd6dbbd688dfe1849963f642222b22",
"url": "https://chromium.googlesource.com/chromium/src/+/fbed9fcd80cd6dbbd688dfe1849963f642222b22"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6b80aef8757059cc7181963e5077609e3b1d5f63"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@6b80aef8757059cc7181963e5077609e3b1d5f63",
"title": "6b80aef8757059cc7181963e5077609e3b1d5f63",
"url": "https://chromium.googlesource.com/chromium/src/+/6b80aef8757059cc7181963e5077609e3b1d5f63"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a09d095c03456b52d3168514f25d2134f05c19f0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@a09d095c03456b52d3168514f25d2134f05c19f0",
"title": "a09d095c03456b52d3168514f25d2134f05c19f0",
"url": "https://chromium.googlesource.com/chromium/src/+/a09d095c03456b52d3168514f25d2134f05c19f0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8c5accf6f9734476f30cbc44bab7256ae1af3459"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@8c5accf6f9734476f30cbc44bab7256ae1af3459",
"title": "8c5accf6f9734476f30cbc44bab7256ae1af3459",
"url": "https://chromium.googlesource.com/chromium/src/+/8c5accf6f9734476f30cbc44bab7256ae1af3459"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b54d282b7b86c7f23669e0de88c557e8205e9ce8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@b54d282b7b86c7f23669e0de88c557e8205e9ce8",
"title": "b54d282b7b86c7f23669e0de88c557e8205e9ce8",
"url": "https://chromium.googlesource.com/chromium/src/+/b54d282b7b86c7f23669e0de88c557e8205e9ce8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cfb8ac1c0a31a1432636ac14a76a368ba1441497"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@cfb8ac1c0a31a1432636ac14a76a368ba1441497",
"title": "cfb8ac1c0a31a1432636ac14a76a368ba1441497",
"url": "https://chromium.googlesource.com/chromium/src/+/cfb8ac1c0a31a1432636ac14a76a368ba1441497"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "cfb8ac1c0a31a1432636ac14a76a368ba1441497",
"ref": "refs/branch-heads/7390"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8868158454745307552"
},
{
"key": "scheduler_job_id",
"value": "chromium-m141/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:27:56.720] Scheduled build:
{
"id": "8702728391807090833",
"builder": {
"project": "chromium-m141",
"bucket": "ci",
"builder": "win-official"
},
"number": 194,
"createdBy": "project:chromium-m141",
"createTime": "2025-09-25T19:27:52.282025443Z",
"updateTime": "2025-09-25T19:27:52.282025443Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "cfb8ac1c0a31a1432636ac14a76a368ba1441497",
"ref": "refs/branch-heads/7390"
}
}
}
[19:27:56.720] Task URL: https://cr-buildbucket.appspot.com/build/8702728391807090833
[19:27:56.720] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:2:0) after 6m46s
[19:27:57.688] Received PubSub notification, asking Buildbucket for the build status
[19:27:57.725] Build status: SCHEDULED
[19:28:01.038] Received PubSub notification, asking Buildbucket for the build status
[19:28:01.062] Build status: STARTED
[19:34:42.737] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:2:0)
[19:34:42.800] Build status: STARTED
[19:34:42.800] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:5:0) after 7m40s
[19:42:22.845] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:5:0)
[19:42:22.878] Build status: STARTED
[19:42:22.878] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:6:0) after 6m36s
[19:48:58.995] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:6:0)
[19:48:59.023] Build status: STARTED
[19:48:59.023] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:7:0) after 4m10s
[19:53:09.099] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:7:0)
[19:53:09.120] Build status: STARTED
[19:53:09.120] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:8:0) after 1m24s
[19:54:33.105] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:8:0)
[19:54:33.131] Build status: STARTED
[19:54:33.132] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:9:0) after 1m2s
[19:55:35.146] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:9:0)
[19:55:35.259] Build status: STARTED
[19:55:35.259] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:10:0) after 1m43s
[19:57:18.328] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:10:0)
[19:57:18.352] Build status: STARTED
[19:57:18.352] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:11:0) after 4m30s
[20:01:48.436] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:11:0)
[20:01:48.456] Build status: STARTED
[20:01:48.456] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:12:0) after 1m37s
[20:03:25.693] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:12:0)
[20:03:25.711] Build status: STARTED
[20:03:25.711] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:13:0) after 4m46s
[20:08:11.946] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:13:0)
[20:08:11.964] Build status: STARTED
[20:08:11.965] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:14:0) after 7m29s
[20:15:41.102] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:14:0)
[20:15:41.126] Build status: STARTED
[20:15:41.126] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:15:0) after 5m16s
[20:20:57.143] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:15:0)
[20:20:57.241] Build status: STARTED
[20:20:57.241] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:16:0) after 4m20s
[20:25:17.277] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:16:0)
[20:25:17.306] Build status: STARTED
[20:25:17.306] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:17:0) after 4m17s
[20:29:34.318] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:17:0)
[20:29:34.334] Build status: STARTED
[20:29:34.334] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:18:0) after 8m0s
[20:37:34.359] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:18:0)
[20:37:34.385] Build status: STARTED
[20:37:34.385] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:19:0) after 3m10s
[20:40:44.585] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:19:0)
[20:40:44.602] Build status: STARTED
[20:40:44.602] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:20:0) after 2m38s
[20:43:22.722] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:20:0)
[20:43:22.738] Build status: STARTED
[20:43:22.738] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:21:0) after 2m35s
[20:45:57.835] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:21:0)
[20:45:57.862] Build status: STARTED
[20:45:57.862] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:22:0) after 3m25s
[20:49:22.943] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:22:0)
[20:49:22.963] Build status: STARTED
[20:49:22.963] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:23:0) after 6m19s
[20:55:41.978] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:23:0)
[20:55:41.994] Build status: STARTED
[20:55:41.994] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:24:0) after 3m15s
[20:58:57.086] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:24:0)
[20:58:57.104] Build status: STARTED
[20:58:57.104] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:25:0) after 1m51s
[21:00:48.126] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:25:0)
[21:00:48.150] Build status: STARTED
[21:00:48.150] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:26:0) after 7m39s
[21:08:27.164] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:26:0)
[21:08:27.186] Build status: STARTED
[21:08:27.186] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:27:0) after 3m46s
[21:12:13.304] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:27:0)
[21:12:13.326] Build status: STARTED
[21:12:13.326] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:28:0) after 2m1s
[21:14:14.404] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:28:0)
[21:14:14.421] Build status: STARTED
[21:14:14.421] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:29:0) after 3m32s
[21:17:46.535] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:29:0)
[21:17:46.655] Build status: STARTED
[21:17:46.656] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:30:0) after 4m32s
[21:22:18.720] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:30:0)
[21:22:18.734] Build status: STARTED
[21:22:18.734] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:31:0) after 1m51s
[21:24:09.763] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:31:0)
[21:24:09.797] Build status: STARTED
[21:24:09.797] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:32:0) after 2m29s
[21:26:38.816] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:32:0)
[21:26:38.840] Build status: STARTED
[21:26:38.840] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:33:0) after 4m40s
[21:31:18.963] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:33:0)
[21:31:19.061] Build status: STARTED
[21:31:19.061] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:34:0) after 5m37s
[21:36:56.086] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:34:0)
[21:36:56.153] Build status: STARTED
[21:36:56.153] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:35:0) after 5m40s
[21:42:36.335] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:35:0)
[21:42:36.359] Build status: STARTED
[21:42:36.359] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:36:0) after 8m56s
[21:51:32.377] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:36:0)
[21:51:32.392] Build status: STARTED
[21:51:32.392] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:37:0) after 1m7s
[21:52:39.411] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:37:0)
[21:52:39.433] Build status: STARTED
[21:52:39.433] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:38:0) after 1m38s
[21:54:17.448] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:38:0)
[21:54:17.482] Build status: STARTED
[21:54:17.482] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:39:0) after 8m17s
[22:02:34.673] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:39:0)
[22:02:34.739] Build status: STARTED
[22:02:34.739] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:40:0) after 1m25s
[22:03:59.806] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:40:0)
[22:03:59.823] Build status: STARTED
[22:03:59.823] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:41:0) after 5m11s
[22:09:10.885] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:41:0)
[22:09:10.905] Build status: STARTED
[22:09:10.905] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:42:0) after 4m28s
[22:13:38.923] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:42:0)
[22:13:38.938] Build status: STARTED
[22:13:38.938] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:43:0) after 7m36s
[22:21:15.134] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:43:0)
[22:21:15.170] Build status: STARTED
[22:21:15.170] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:44:0) after 2m24s
[22:23:39.200] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:44:0)
[22:23:39.229] Build status: STARTED
[22:23:39.229] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:45:0) after 4m28s
[22:28:07.249] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:45:0)
[22:28:07.286] Build status: STARTED
[22:28:07.286] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8868158454745307552:46:0) after 3m11s
[22:30:54.790] Received PubSub notification, asking Buildbucket for the build status
[22:30:54.828] Build:
{
"id": "8702728391807090833",
"builder": {
"project": "chromium-m141",
"bucket": "ci",
"builder": "win-official"
},
"number": 194,
"createdBy": "project:chromium-m141",
"createTime": "2025-09-25T19:27:52.282025443Z",
"startTime": "2025-09-25T19:28:00.473015777Z",
"endTime": "2025-09-25T22:30:54.098710791Z",
"updateTime": "2025-09-25T22:30:54.098710791Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "cfb8ac1c0a31a1432636ac14a76a368ba1441497",
"ref": "refs/branch-heads/7390"
}
}
}
[22:30:54.828] Invocation finished in 3h3m4.732981594s with status SUCCEEDED