[02:07:39.014] New invocation is queued and will start shortly
[02:07:40.153] Starting the invocation (attempt 1)
[02:07:40.182] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/354c03217e320a3890bb459d3b0b1902f5d803a2"
[02:07:40.182] Popped gitiles commit info from properties and tags
[02:07:40.182] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:07:40.182] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:07:40.182] Buildbucket request:
{
"requestId": "8849470325468109984",
"builder": {
"project": "chromium-m147",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8849470325468109984",
"job": "chromium-m147/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "637b71563dbec6eef1207bb1e7cf55df684c55f5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@637b71563dbec6eef1207bb1e7cf55df684c55f5",
"title": "637b71563dbec6eef1207bb1e7cf55df684c55f5",
"url": "https://chromium.googlesource.com/chromium/src/+/637b71563dbec6eef1207bb1e7cf55df684c55f5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "14da71b0d2dcb8e1a1aea0cf42404d581028bc6d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@14da71b0d2dcb8e1a1aea0cf42404d581028bc6d",
"title": "14da71b0d2dcb8e1a1aea0cf42404d581028bc6d",
"url": "https://chromium.googlesource.com/chromium/src/+/14da71b0d2dcb8e1a1aea0cf42404d581028bc6d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ca16941a5f9530921603e265978e1bb4327e6a20"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@ca16941a5f9530921603e265978e1bb4327e6a20",
"title": "ca16941a5f9530921603e265978e1bb4327e6a20",
"url": "https://chromium.googlesource.com/chromium/src/+/ca16941a5f9530921603e265978e1bb4327e6a20"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7b4afe5901fdedb23d6ebdf3b6f79d6d6f9f2e79"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@7b4afe5901fdedb23d6ebdf3b6f79d6d6f9f2e79",
"title": "7b4afe5901fdedb23d6ebdf3b6f79d6d6f9f2e79",
"url": "https://chromium.googlesource.com/chromium/src/+/7b4afe5901fdedb23d6ebdf3b6f79d6d6f9f2e79"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1c18dc5f82f75c8d9ad6dc42de5693dff26293d0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@1c18dc5f82f75c8d9ad6dc42de5693dff26293d0",
"title": "1c18dc5f82f75c8d9ad6dc42de5693dff26293d0",
"url": "https://chromium.googlesource.com/chromium/src/+/1c18dc5f82f75c8d9ad6dc42de5693dff26293d0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8eb29d260bc122a2a13e6309a4e8688b12f3e002"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@8eb29d260bc122a2a13e6309a4e8688b12f3e002",
"title": "8eb29d260bc122a2a13e6309a4e8688b12f3e002",
"url": "https://chromium.googlesource.com/chromium/src/+/8eb29d260bc122a2a13e6309a4e8688b12f3e002"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "354c03217e320a3890bb459d3b0b1902f5d803a2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@354c03217e320a3890bb459d3b0b1902f5d803a2",
"title": "354c03217e320a3890bb459d3b0b1902f5d803a2",
"url": "https://chromium.googlesource.com/chromium/src/+/354c03217e320a3890bb459d3b0b1902f5d803a2"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "354c03217e320a3890bb459d3b0b1902f5d803a2",
"ref": "refs/branch-heads/7727"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8849470325468109984"
},
{
"key": "scheduler_job_id",
"value": "chromium-m147/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:07:41.002] Scheduled build:
{
"id": "8684040263451627713",
"builder": {
"project": "chromium-m147",
"bucket": "ci",
"builder": "win-official"
},
"number": 297,
"createdBy": "project:chromium-m147",
"createTime": "2026-04-20T02:07:40.316195777Z",
"updateTime": "2026-04-20T02:07:40.316195777Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "354c03217e320a3890bb459d3b0b1902f5d803a2",
"ref": "refs/branch-heads/7727"
}
}
}
[02:07:41.002] Task URL: https://cr-buildbucket.appspot.com/build/8684040263451627713
[02:07:41.002] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:2:0) after 9m27s
[02:07:41.323] Received PubSub notification, asking Buildbucket for the build status
[02:07:41.368] Build status: SCHEDULED
[02:07:45.568] Received PubSub notification, asking Buildbucket for the build status
[02:07:45.621] Build status: STARTED
[02:17:08.172] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:2:0)
[02:17:08.204] Build status: STARTED
[02:17:08.204] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:5:0) after 9m42s
[02:26:50.221] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:5:0)
[02:26:50.242] Build status: STARTED
[02:26:50.242] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:6:0) after 5m14s
[02:32:04.291] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:6:0)
[02:32:04.312] Build status: STARTED
[02:32:04.312] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:7:0) after 7m55s
[02:39:59.322] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:7:0)
[02:39:59.342] Build status: STARTED
[02:39:59.342] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:8:0) after 6m31s
[02:46:30.359] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:8:0)
[02:46:30.385] Build status: STARTED
[02:46:30.385] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:9:0) after 9m0s
[02:55:30.452] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:9:0)
[02:55:30.477] Build status: STARTED
[02:55:30.477] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:10:0) after 3m27s
[02:58:57.498] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:10:0)
[02:58:57.597] Build status: STARTED
[02:58:57.597] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:11:0) after 6m56s
[03:05:53.665] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:11:0)
[03:05:53.692] Build status: STARTED
[03:05:53.692] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:12:0) after 3m15s
[03:09:08.744] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:12:0)
[03:09:08.773] Build status: STARTED
[03:09:08.773] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:13:0) after 2m53s
[03:12:01.797] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:13:0)
[03:12:01.843] Build status: STARTED
[03:12:01.843] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:14:0) after 3m35s
[03:15:36.856] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:14:0)
[03:15:36.874] Build status: STARTED
[03:15:36.874] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:15:0) after 3m19s
[03:18:55.887] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:15:0)
[03:18:55.902] Build status: STARTED
[03:18:55.902] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:16:0) after 5m16s
[03:24:11.916] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:16:0)
[03:24:11.946] Build status: STARTED
[03:24:11.946] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:17:0) after 4m20s
[03:28:31.969] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:17:0)
[03:28:32.007] Build status: STARTED
[03:28:32.007] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:18:0) after 7m1s
[03:35:33.117] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:18:0)
[03:35:33.143] Build status: STARTED
[03:35:33.143] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:19:0) after 5m32s
[03:41:05.156] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:19:0)
[03:41:05.174] Build status: STARTED
[03:41:05.174] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:20:0) after 7m18s
[03:48:23.208] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:20:0)
[03:48:23.223] Build status: STARTED
[03:48:23.223] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:21:0) after 9m53s
[03:58:16.294] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:21:0)
[03:58:16.315] Build status: STARTED
[03:58:16.315] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:22:0) after 6m28s
[04:04:44.589] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:22:0)
[04:04:44.620] Build status: STARTED
[04:04:44.620] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:23:0) after 5m32s
[04:10:16.641] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:23:0)
[04:10:16.670] Build status: STARTED
[04:10:16.670] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:24:0) after 4m59s
[04:15:16.191] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:24:0)
[04:15:16.243] Build status: STARTED
[04:15:16.243] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:25:0) after 3m24s
[04:18:40.315] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:25:0)
[04:18:40.341] Build status: STARTED
[04:18:40.341] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:26:0) after 3m8s
[04:21:48.452] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:26:0)
[04:21:48.483] Build status: STARTED
[04:21:48.483] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:27:0) after 9m58s
[04:31:46.872] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:27:0)
[04:31:46.945] Build status: STARTED
[04:31:46.945] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:28:0) after 7m1s
[04:38:47.963] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:28:0)
[04:38:47.985] Build status: STARTED
[04:38:47.985] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:29:0) after 2m27s
[04:41:15.064] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:29:0)
[04:41:15.077] Build status: STARTED
[04:41:15.077] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:30:0) after 5m0s
[04:46:15.095] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:30:0)
[04:46:15.143] Build status: STARTED
[04:46:15.143] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:31:0) after 8m44s
[04:54:59.158] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:31:0)
[04:54:59.186] Build status: STARTED
[04:54:59.186] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:32:0) after 6m51s
[05:01:50.188] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:32:0)
[05:01:50.215] Build status: STARTED
[05:01:50.215] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:33:0) after 3m48s
[05:05:38.232] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:33:0)
[05:05:38.264] Build status: STARTED
[05:05:38.264] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:34:0) after 7m1s
[05:12:39.279] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:34:0)
[05:12:39.297] Build status: STARTED
[05:12:39.297] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849470325468109984:35:0) after 6m21s
[05:18:43.635] Received PubSub notification, asking Buildbucket for the build status
[05:18:43.651] Build:
{
"id": "8684040263451627713",
"builder": {
"project": "chromium-m147",
"bucket": "ci",
"builder": "win-official"
},
"number": 297,
"createdBy": "project:chromium-m147",
"createTime": "2026-04-20T02:07:40.316195777Z",
"startTime": "2026-04-20T02:07:45.130512577Z",
"endTime": "2026-04-20T05:18:43.230063151Z",
"updateTime": "2026-04-20T05:18:43.230063151Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "354c03217e320a3890bb459d3b0b1902f5d803a2",
"ref": "refs/branch-heads/7727"
}
}
}
[05:18:43.651] Invocation finished in 3h11m4.642123462s with status SUCCEEDED