[18:59:42.486] New invocation is queued and will start shortly
[18:59:43.545] Starting the invocation (attempt 1)
[18:59:43.572] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/653c2b2f3db4caeb433b103e3f34438436fcd736"
[18:59:43.572] Popped gitiles commit info from properties and tags
[18:59:43.572] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:59:43.573] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:59:43.574] Buildbucket request:
{
"requestId": "8850403218928612720",
"builder": {
"project": "chromium-m147",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8850403218928612720",
"job": "chromium-m147/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "15ecb68652ddd77a50c8fb2793b55fdd3377e521"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@15ecb68652ddd77a50c8fb2793b55fdd3377e521",
"title": "15ecb68652ddd77a50c8fb2793b55fdd3377e521",
"url": "https://chromium.googlesource.com/chromium/src/+/15ecb68652ddd77a50c8fb2793b55fdd3377e521"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3d9580d0857044a1e3a57d4a94af75721903ea60"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@3d9580d0857044a1e3a57d4a94af75721903ea60",
"title": "3d9580d0857044a1e3a57d4a94af75721903ea60",
"url": "https://chromium.googlesource.com/chromium/src/+/3d9580d0857044a1e3a57d4a94af75721903ea60"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c9cb17460950784550b3628cdd930103315101c1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@c9cb17460950784550b3628cdd930103315101c1",
"title": "c9cb17460950784550b3628cdd930103315101c1",
"url": "https://chromium.googlesource.com/chromium/src/+/c9cb17460950784550b3628cdd930103315101c1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fa5f4fa2f3f1d1c19cd749029e1ddea9494578e2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@fa5f4fa2f3f1d1c19cd749029e1ddea9494578e2",
"title": "fa5f4fa2f3f1d1c19cd749029e1ddea9494578e2",
"url": "https://chromium.googlesource.com/chromium/src/+/fa5f4fa2f3f1d1c19cd749029e1ddea9494578e2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a685bb736b4b12ac3d846cc65b95677b4e8cb62a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@a685bb736b4b12ac3d846cc65b95677b4e8cb62a",
"title": "a685bb736b4b12ac3d846cc65b95677b4e8cb62a",
"url": "https://chromium.googlesource.com/chromium/src/+/a685bb736b4b12ac3d846cc65b95677b4e8cb62a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d0da6e267166a80093e02bc31c085196f1c602f9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@d0da6e267166a80093e02bc31c085196f1c602f9",
"title": "d0da6e267166a80093e02bc31c085196f1c602f9",
"url": "https://chromium.googlesource.com/chromium/src/+/d0da6e267166a80093e02bc31c085196f1c602f9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7670c0bb712a5658143775184ce995d84ece5603"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@7670c0bb712a5658143775184ce995d84ece5603",
"title": "7670c0bb712a5658143775184ce995d84ece5603",
"url": "https://chromium.googlesource.com/chromium/src/+/7670c0bb712a5658143775184ce995d84ece5603"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "19a30e1dda71f8e9b0daa06a596fc2aa60369971"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@19a30e1dda71f8e9b0daa06a596fc2aa60369971",
"title": "19a30e1dda71f8e9b0daa06a596fc2aa60369971",
"url": "https://chromium.googlesource.com/chromium/src/+/19a30e1dda71f8e9b0daa06a596fc2aa60369971"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1bbb828f63afb6eb5b0bf45b5cef58f256cd8c49"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@1bbb828f63afb6eb5b0bf45b5cef58f256cd8c49",
"title": "1bbb828f63afb6eb5b0bf45b5cef58f256cd8c49",
"url": "https://chromium.googlesource.com/chromium/src/+/1bbb828f63afb6eb5b0bf45b5cef58f256cd8c49"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "04a28f68f65c8a9acefb4f587bd2dd4d96e1a656"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@04a28f68f65c8a9acefb4f587bd2dd4d96e1a656",
"title": "04a28f68f65c8a9acefb4f587bd2dd4d96e1a656",
"url": "https://chromium.googlesource.com/chromium/src/+/04a28f68f65c8a9acefb4f587bd2dd4d96e1a656"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b88280138aaa93757d51cc9d4b624019af885435"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@b88280138aaa93757d51cc9d4b624019af885435",
"title": "b88280138aaa93757d51cc9d4b624019af885435",
"url": "https://chromium.googlesource.com/chromium/src/+/b88280138aaa93757d51cc9d4b624019af885435"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f59255c073efc19b6fba0a938571d4ef6d9a627a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@f59255c073efc19b6fba0a938571d4ef6d9a627a",
"title": "f59255c073efc19b6fba0a938571d4ef6d9a627a",
"url": "https://chromium.googlesource.com/chromium/src/+/f59255c073efc19b6fba0a938571d4ef6d9a627a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "653c2b2f3db4caeb433b103e3f34438436fcd736"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@653c2b2f3db4caeb433b103e3f34438436fcd736",
"title": "653c2b2f3db4caeb433b103e3f34438436fcd736",
"url": "https://chromium.googlesource.com/chromium/src/+/653c2b2f3db4caeb433b103e3f34438436fcd736"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "653c2b2f3db4caeb433b103e3f34438436fcd736",
"ref": "refs/branch-heads/7727"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8850403218928612720"
},
{
"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"
}
}
[18:59:44.427] Scheduled build:
{
"id": "8684973157065219729",
"builder": {
"project": "chromium-m147",
"bucket": "ci",
"builder": "win-official"
},
"number": 223,
"createdBy": "project:chromium-m147",
"createTime": "2026-04-09T18:59:43.637484400Z",
"updateTime": "2026-04-09T18:59:43.637484400Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "653c2b2f3db4caeb433b103e3f34438436fcd736",
"ref": "refs/branch-heads/7727"
}
}
}
[18:59:44.427] Task URL: https://cr-buildbucket.appspot.com/build/8684973157065219729
[18:59:44.427] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:2:0) after 7m56s
[18:59:45.103] Received PubSub notification, asking Buildbucket for the build status
[18:59:45.131] Build status: SCHEDULED
[18:59:48.637] Received PubSub notification, asking Buildbucket for the build status
[18:59:48.661] Build status: STARTED
[19:07:40.513] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:2:0)
[19:07:40.546] Build status: STARTED
[19:07:40.546] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:5:0) after 8m53s
[19:16:33.570] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:5:0)
[19:16:33.599] Build status: STARTED
[19:16:33.599] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:6:0) after 4m0s
[19:20:33.708] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:6:0)
[19:20:33.725] Build status: STARTED
[19:20:33.725] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:7:0) after 6m58s
[19:27:31.718] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:7:0)
[19:27:31.741] Build status: STARTED
[19:27:31.741] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:8:0) after 9m12s
[19:36:43.763] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:8:0)
[19:36:43.801] Build status: STARTED
[19:36:43.801] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:9:0) after 7m17s
[19:44:00.877] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:9:0)
[19:44:00.894] Build status: STARTED
[19:44:00.894] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:10:0) after 6m39s
[19:50:39.995] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:10:0)
[19:50:40.063] Build status: STARTED
[19:50:40.063] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:11:0) after 9m18s
[19:59:58.117] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:11:0)
[19:59:58.139] Build status: STARTED
[19:59:58.139] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:12:0) after 5m29s
[20:05:27.216] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:12:0)
[20:05:27.237] Build status: STARTED
[20:05:27.237] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:13:0) after 6m25s
[20:11:52.252] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:13:0)
[20:11:52.352] Build status: STARTED
[20:11:52.352] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:14:0) after 5m34s
[20:17:26.456] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:14:0)
[20:17:26.474] Build status: STARTED
[20:17:26.474] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:15:0) after 2m41s
[20:20:07.592] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:15:0)
[20:20:07.605] Build status: STARTED
[20:20:07.605] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:16:0) after 6m42s
[20:26:49.674] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:16:0)
[20:26:49.757] Build status: STARTED
[20:26:49.757] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:17:0) after 2m31s
[20:29:20.773] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:17:0)
[20:29:20.790] Build status: STARTED
[20:29:20.790] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:18:0) after 4m31s
[20:33:51.813] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:18:0)
[20:33:51.833] Build status: STARTED
[20:33:51.833] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:19:0) after 1m8s
[20:34:59.858] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:19:0)
[20:34:59.875] Build status: STARTED
[20:34:59.875] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:20:0) after 6m28s
[20:41:27.897] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:20:0)
[20:41:27.918] Build status: STARTED
[20:41:27.918] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:21:0) after 9m43s
[20:51:11.115] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:21:0)
[20:51:11.148] Build status: STARTED
[20:51:11.148] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:22:0) after 3m25s
[20:54:36.125] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:22:0)
[20:54:36.153] Build status: STARTED
[20:54:36.153] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:23:0) after 1m22s
[20:55:58.194] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:23:0)
[20:55:58.229] Build status: STARTED
[20:55:58.229] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:24:0) after 1m27s
[20:57:25.325] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:24:0)
[20:57:25.343] Build status: STARTED
[20:57:25.343] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:25:0) after 3m59s
[21:01:24.383] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:25:0)
[21:01:24.536] Build status: STARTED
[21:01:24.536] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:26:0) after 9m23s
[21:10:47.783] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:26:0)
[21:10:47.807] Build status: STARTED
[21:10:47.807] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:27:0) after 9m24s
[21:20:11.845] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:27:0)
[21:20:11.865] Build status: STARTED
[21:20:11.865] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:28:0) after 1m57s
[21:22:08.986] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:28:0)
[21:22:09.074] Build status: STARTED
[21:22:09.074] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:29:0) after 4m27s
[21:26:36.123] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:29:0)
[21:26:36.148] Build status: STARTED
[21:26:36.148] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:30:0) after 8m36s
[21:35:12.174] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:30:0)
[21:35:12.199] Build status: STARTED
[21:35:12.199] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:31:0) after 4m3s
[21:39:15.191] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:31:0)
[21:39:15.224] Build status: STARTED
[21:39:15.224] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:32:0) after 1m28s
[21:40:43.639] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:32:0)
[21:40:43.655] Build status: STARTED
[21:40:43.655] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:33:0) after 2m59s
[21:43:42.752] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:33:0)
[21:43:42.947] Build status: STARTED
[21:43:42.947] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:34:0) after 8m53s
[21:52:35.936] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:34:0)
[21:52:36.039] Build status: STARTED
[21:52:36.039] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:35:0) after 4m31s
[21:57:07.119] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:35:0)
[21:57:07.155] Build status: STARTED
[21:57:07.155] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850403218928612720:36:0) after 4m35s
[21:59:57.681] Received PubSub notification, asking Buildbucket for the build status
[21:59:57.710] Build:
{
"id": "8684973157065219729",
"builder": {
"project": "chromium-m147",
"bucket": "ci",
"builder": "win-official"
},
"number": 223,
"createdBy": "project:chromium-m147",
"createTime": "2026-04-09T18:59:43.637484400Z",
"startTime": "2026-04-09T18:59:48.386223081Z",
"endTime": "2026-04-09T21:57:30.726771310Z",
"updateTime": "2026-04-09T21:57:30.726771310Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "653c2b2f3db4caeb433b103e3f34438436fcd736",
"ref": "refs/branch-heads/7727"
}
}
}
[21:59:57.710] Invocation finished in 3h0m15.233477673s with status SUCCEEDED