[08:58:22.821] New invocation is queued and will start shortly
[08:58:23.830] Starting the invocation (attempt 1)
[08:58:23.857] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bda088ab5c940e527f0fba173ee07a45a8d7e909"
[08:58:23.857] Popped gitiles commit info from properties and tags
[08:58:23.857] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:58:23.858] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:58:23.858] Buildbucket request:
{
"requestId": "8855695675245203536",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8855695675245203536",
"job": "chromium-m145/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "75116955925c740f8d0283559dc4763e982dad93"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@75116955925c740f8d0283559dc4763e982dad93",
"title": "75116955925c740f8d0283559dc4763e982dad93",
"url": "https://chromium.googlesource.com/chromium/src/+/75116955925c740f8d0283559dc4763e982dad93"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9225a88d43b32cc38df6f03af8aa5ac65fed189b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@9225a88d43b32cc38df6f03af8aa5ac65fed189b",
"title": "9225a88d43b32cc38df6f03af8aa5ac65fed189b",
"url": "https://chromium.googlesource.com/chromium/src/+/9225a88d43b32cc38df6f03af8aa5ac65fed189b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "973b27bed9a5e93de38a13a467ca76ff8076421c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@973b27bed9a5e93de38a13a467ca76ff8076421c",
"title": "973b27bed9a5e93de38a13a467ca76ff8076421c",
"url": "https://chromium.googlesource.com/chromium/src/+/973b27bed9a5e93de38a13a467ca76ff8076421c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c8934791dfd06fb09147314086f97640c54dc6fb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@c8934791dfd06fb09147314086f97640c54dc6fb",
"title": "c8934791dfd06fb09147314086f97640c54dc6fb",
"url": "https://chromium.googlesource.com/chromium/src/+/c8934791dfd06fb09147314086f97640c54dc6fb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "898e998b4b8b0e3727074a7de3838eb7d0d04231"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@898e998b4b8b0e3727074a7de3838eb7d0d04231",
"title": "898e998b4b8b0e3727074a7de3838eb7d0d04231",
"url": "https://chromium.googlesource.com/chromium/src/+/898e998b4b8b0e3727074a7de3838eb7d0d04231"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "161efff23dbaef3dd2e1e5d21b43b3d53c2a0823"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@161efff23dbaef3dd2e1e5d21b43b3d53c2a0823",
"title": "161efff23dbaef3dd2e1e5d21b43b3d53c2a0823",
"url": "https://chromium.googlesource.com/chromium/src/+/161efff23dbaef3dd2e1e5d21b43b3d53c2a0823"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "dc19b34f5ae744b82bcc2ff4b8153b92dff0f925"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@dc19b34f5ae744b82bcc2ff4b8153b92dff0f925",
"title": "dc19b34f5ae744b82bcc2ff4b8153b92dff0f925",
"url": "https://chromium.googlesource.com/chromium/src/+/dc19b34f5ae744b82bcc2ff4b8153b92dff0f925"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1bab3280bb314ec42b7077d6fa5e082c04f8c777"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@1bab3280bb314ec42b7077d6fa5e082c04f8c777",
"title": "1bab3280bb314ec42b7077d6fa5e082c04f8c777",
"url": "https://chromium.googlesource.com/chromium/src/+/1bab3280bb314ec42b7077d6fa5e082c04f8c777"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bda088ab5c940e527f0fba173ee07a45a8d7e909"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@bda088ab5c940e527f0fba173ee07a45a8d7e909",
"title": "bda088ab5c940e527f0fba173ee07a45a8d7e909",
"url": "https://chromium.googlesource.com/chromium/src/+/bda088ab5c940e527f0fba173ee07a45a8d7e909"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bda088ab5c940e527f0fba173ee07a45a8d7e909",
"ref": "refs/branch-heads/7632"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8855695675245203536"
},
{
"key": "scheduler_job_id",
"value": "chromium-m145/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[08:58:24.388] Scheduled build:
{
"id": "8690265613455135729",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win-official"
},
"number": 214,
"createdBy": "project:chromium-m145",
"createTime": "2026-02-10T08:58:23.907444527Z",
"updateTime": "2026-02-10T08:58:23.907444527Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bda088ab5c940e527f0fba173ee07a45a8d7e909",
"ref": "refs/branch-heads/7632"
}
}
}
[08:58:24.388] Task URL: https://cr-buildbucket.appspot.com/build/8690265613455135729
[08:58:24.388] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:2:0) after 1m4s
[08:58:25.288] Received PubSub notification, asking Buildbucket for the build status
[08:58:25.356] Build status: SCHEDULED
[08:58:28.837] Received PubSub notification, asking Buildbucket for the build status
[08:58:28.863] Build status: STARTED
[08:59:28.369] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:2:0)
[08:59:28.391] Build status: STARTED
[08:59:28.391] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:5:0) after 9m54s
[09:09:22.403] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:5:0)
[09:09:22.425] Build status: STARTED
[09:09:22.425] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:6:0) after 3m28s
[09:12:50.437] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:6:0)
[09:12:50.454] Build status: STARTED
[09:12:50.454] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:7:0) after 2m27s
[09:15:17.470] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:7:0)
[09:15:17.515] Build status: STARTED
[09:15:17.515] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:8:0) after 3m2s
[09:18:19.554] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:8:0)
[09:18:19.698] Build status: STARTED
[09:18:19.698] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:9:0) after 9m51s
[09:28:10.763] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:9:0)
[09:28:10.781] Build status: STARTED
[09:28:10.781] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:10:0) after 2m31s
[09:30:41.800] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:10:0)
[09:30:41.816] Build status: STARTED
[09:30:41.816] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:11:0) after 9m27s
[09:40:08.832] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:11:0)
[09:40:08.855] Build status: STARTED
[09:40:08.855] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:12:0) after 3m48s
[09:43:56.873] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:12:0)
[09:43:56.902] Build status: STARTED
[09:43:56.903] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:13:0) after 3m52s
[09:47:48.918] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:13:0)
[09:47:48.935] Build status: STARTED
[09:47:48.935] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:14:0) after 9m23s
[09:57:12.039] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:14:0)
[09:57:12.068] Build status: STARTED
[09:57:12.068] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:15:0) after 5m54s
[10:03:06.087] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:15:0)
[10:03:06.105] Build status: STARTED
[10:03:06.105] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:16:0) after 5m14s
[10:08:20.229] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:16:0)
[10:08:20.247] Build status: STARTED
[10:08:20.247] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:17:0) after 6m13s
[10:14:33.224] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:17:0)
[10:14:33.251] Build status: STARTED
[10:14:33.251] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:18:0) after 6m21s
[10:20:54.318] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:18:0)
[10:20:54.336] Build status: STARTED
[10:20:54.336] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:19:0) after 3m25s
[10:24:19.387] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:19:0)
[10:24:19.402] Build status: STARTED
[10:24:19.402] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:20:0) after 2m55s
[10:27:14.467] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:20:0)
[10:27:14.488] Build status: STARTED
[10:27:14.488] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:21:0) after 9m43s
[10:36:57.509] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:21:0)
[10:36:57.528] Build status: STARTED
[10:36:57.528] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:22:0) after 2m36s
[10:39:33.551] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:22:0)
[10:39:33.566] Build status: STARTED
[10:39:33.566] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:23:0) after 8m59s
[10:48:32.557] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:23:0)
[10:48:32.589] Build status: STARTED
[10:48:32.589] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:24:0) after 7m23s
[10:55:55.597] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:24:0)
[10:55:55.613] Build status: STARTED
[10:55:55.613] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:25:0) after 1m6s
[10:57:01.630] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:25:0)
[10:57:01.705] Build status: STARTED
[10:57:01.705] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:26:0) after 2m5s
[10:59:06.734] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:26:0)
[10:59:06.764] Build status: STARTED
[10:59:06.764] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:27:0) after 6m20s
[11:05:26.782] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:27:0)
[11:05:26.801] Build status: STARTED
[11:05:26.801] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:28:0) after 1m24s
[11:06:50.876] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:28:0)
[11:06:50.892] Build status: STARTED
[11:06:50.892] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:29:0) after 1m59s
[11:08:50.007] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:29:0)
[11:08:50.023] Build status: STARTED
[11:08:50.023] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:30:0) after 7m27s
[11:16:17.131] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:30:0)
[11:16:17.152] Build status: STARTED
[11:16:17.152] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:31:0) after 8m6s
[11:24:23.167] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:31:0)
[11:24:23.181] Build status: STARTED
[11:24:23.181] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:32:0) after 9m16s
[11:33:39.196] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:32:0)
[11:33:39.371] Build status: STARTED
[11:33:39.371] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:33:0) after 4m51s
[11:38:30.350] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:33:0)
[11:38:30.388] Build status: STARTED
[11:38:30.388] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:34:0) after 8m25s
[11:46:55.402] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:34:0)
[11:46:55.421] Build status: STARTED
[11:46:55.421] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:35:0) after 2m44s
[11:49:39.450] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:35:0)
[11:49:39.511] Build status: STARTED
[11:49:39.511] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:36:0) after 1m57s
[11:51:36.578] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:36:0)
[11:51:36.592] Build status: STARTED
[11:51:36.592] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:37:0) after 4m56s
[11:56:32.691] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:37:0)
[11:56:32.712] Build status: STARTED
[11:56:32.712] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:38:0) after 9m28s
[12:06:00.832] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:38:0)
[12:06:00.929] Build status: STARTED
[12:06:00.929] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:39:0) after 4m44s
[12:10:44.942] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:39:0)
[12:10:44.966] Build status: STARTED
[12:10:44.967] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:40:0) after 2m3s
[12:12:48.049] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:40:0)
[12:12:48.074] Build status: STARTED
[12:12:48.074] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:41:0) after 5m4s
[12:17:52.090] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:41:0)
[12:17:52.117] Build status: STARTED
[12:17:52.117] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855695675245203536:42:0) after 6m17s
[12:19:57.623] Received PubSub notification, asking Buildbucket for the build status
[12:19:57.647] Build:
{
"id": "8690265613455135729",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win-official"
},
"number": 214,
"createdBy": "project:chromium-m145",
"createTime": "2026-02-10T08:58:23.907444527Z",
"startTime": "2026-02-10T08:58:28.508784648Z",
"endTime": "2026-02-10T12:19:57.238215158Z",
"updateTime": "2026-02-10T12:19:57.238215158Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bda088ab5c940e527f0fba173ee07a45a8d7e909",
"ref": "refs/branch-heads/7632"
}
}
}
[12:19:57.647] Invocation finished in 3h21m34.831306142s with status SUCCEEDED