[20:54:48.278] New invocation is queued and will start shortly
[20:54:49.468] Starting the invocation (attempt 1)
[20:54:49.497] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cf2466b14a17f40b9945b3a1f73f7f2081ebba2b"
[20:54:49.497] Popped gitiles commit info from properties and tags
[20:54:49.497] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:54:49.497] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:54:49.497] Buildbucket request:
{
"requestId": "8882104915927000032",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8882104915927000032",
"job": "chromium-m136/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7e912d915a82f8739160fed1c03ae36b6344a0cb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@7e912d915a82f8739160fed1c03ae36b6344a0cb",
"title": "7e912d915a82f8739160fed1c03ae36b6344a0cb",
"url": "https://chromium.googlesource.com/chromium/src/+/7e912d915a82f8739160fed1c03ae36b6344a0cb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a5ab873a0ed45555182b0d159a9c46f8283a5bb7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@a5ab873a0ed45555182b0d159a9c46f8283a5bb7",
"title": "a5ab873a0ed45555182b0d159a9c46f8283a5bb7",
"url": "https://chromium.googlesource.com/chromium/src/+/a5ab873a0ed45555182b0d159a9c46f8283a5bb7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8c23074fcbd5389cb7029ad926c2d84960e328e6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@8c23074fcbd5389cb7029ad926c2d84960e328e6",
"title": "8c23074fcbd5389cb7029ad926c2d84960e328e6",
"url": "https://chromium.googlesource.com/chromium/src/+/8c23074fcbd5389cb7029ad926c2d84960e328e6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a038230e1e91dfb69a36d7e60bbb304eb26151fb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@a038230e1e91dfb69a36d7e60bbb304eb26151fb",
"title": "a038230e1e91dfb69a36d7e60bbb304eb26151fb",
"url": "https://chromium.googlesource.com/chromium/src/+/a038230e1e91dfb69a36d7e60bbb304eb26151fb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b980e96cbee11142720ea36bb4f60605ea054352"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@b980e96cbee11142720ea36bb4f60605ea054352",
"title": "b980e96cbee11142720ea36bb4f60605ea054352",
"url": "https://chromium.googlesource.com/chromium/src/+/b980e96cbee11142720ea36bb4f60605ea054352"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ee690864f5ace3259af25e0d08979d624b95ae4e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@ee690864f5ace3259af25e0d08979d624b95ae4e",
"title": "ee690864f5ace3259af25e0d08979d624b95ae4e",
"url": "https://chromium.googlesource.com/chromium/src/+/ee690864f5ace3259af25e0d08979d624b95ae4e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "156fced4a4ebfa8a87c89e271eddf78b5ad03a99"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@156fced4a4ebfa8a87c89e271eddf78b5ad03a99",
"title": "156fced4a4ebfa8a87c89e271eddf78b5ad03a99",
"url": "https://chromium.googlesource.com/chromium/src/+/156fced4a4ebfa8a87c89e271eddf78b5ad03a99"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "81661d00f8d355307e899c57c1b7986b20b8424b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@81661d00f8d355307e899c57c1b7986b20b8424b",
"title": "81661d00f8d355307e899c57c1b7986b20b8424b",
"url": "https://chromium.googlesource.com/chromium/src/+/81661d00f8d355307e899c57c1b7986b20b8424b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "28b800c80b6404c51bc457192a17e5704df9be18"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@28b800c80b6404c51bc457192a17e5704df9be18",
"title": "28b800c80b6404c51bc457192a17e5704df9be18",
"url": "https://chromium.googlesource.com/chromium/src/+/28b800c80b6404c51bc457192a17e5704df9be18"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cf2466b14a17f40b9945b3a1f73f7f2081ebba2b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@cf2466b14a17f40b9945b3a1f73f7f2081ebba2b",
"title": "cf2466b14a17f40b9945b3a1f73f7f2081ebba2b",
"url": "https://chromium.googlesource.com/chromium/src/+/cf2466b14a17f40b9945b3a1f73f7f2081ebba2b"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "cf2466b14a17f40b9945b3a1f73f7f2081ebba2b",
"ref": "refs/branch-heads/7103"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8882104915927000032"
},
{
"key": "scheduler_job_id",
"value": "chromium-m136/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:54:50.370] Scheduled build:
{
"id": "8716674853910453329",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"number": 156,
"createdBy": "project:chromium-m136",
"createTime": "2025-04-24T20:54:49.569664527Z",
"updateTime": "2025-04-24T20:54:49.569664527Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "cf2466b14a17f40b9945b3a1f73f7f2081ebba2b",
"ref": "refs/branch-heads/7103"
}
}
}
[20:54:50.370] Task URL: https://cr-buildbucket.appspot.com/build/8716674853910453329
[20:54:50.370] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:2:0) after 6m53s
[20:54:51.157] Received PubSub notification, asking Buildbucket for the build status
[20:54:51.198] Build status: SCHEDULED
[20:56:19.815] Received PubSub notification, asking Buildbucket for the build status
[20:56:19.840] Build status: STARTED
[21:01:43.491] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:2:0)
[21:01:43.514] Build status: STARTED
[21:01:43.515] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:5:0) after 4m53s
[21:06:36.524] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:5:0)
[21:06:36.551] Build status: STARTED
[21:06:36.551] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:6:0) after 5m49s
[21:12:25.540] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:6:0)
[21:12:25.566] Build status: STARTED
[21:12:25.566] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:7:0) after 7m41s
[21:20:06.586] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:7:0)
[21:20:06.610] Build status: STARTED
[21:20:06.610] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:8:0) after 6m51s
[21:26:57.633] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:8:0)
[21:26:57.653] Build status: STARTED
[21:26:57.653] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:9:0) after 4m46s
[21:31:43.698] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:9:0)
[21:31:43.719] Build status: STARTED
[21:31:43.719] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:10:0) after 4m40s
[21:36:23.805] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:10:0)
[21:36:23.822] Build status: STARTED
[21:36:23.822] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:11:0) after 5m55s
[21:42:18.894] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:11:0)
[21:42:18.912] Build status: STARTED
[21:42:18.912] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:12:0) after 5m55s
[21:48:13.930] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:12:0)
[21:48:13.948] Build status: STARTED
[21:48:13.948] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:13:0) after 1m24s
[21:49:37.966] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:13:0)
[21:49:37.985] Build status: STARTED
[21:49:37.985] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:14:0) after 6m15s
[21:55:53.017] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:14:0)
[21:55:53.038] Build status: STARTED
[21:55:53.038] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:15:0) after 4m43s
[22:00:36.058] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:15:0)
[22:00:36.080] Build status: STARTED
[22:00:36.080] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:16:0) after 7m0s
[22:07:36.123] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:16:0)
[22:07:36.153] Build status: STARTED
[22:07:36.153] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:17:0) after 9m19s
[22:16:55.197] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:17:0)
[22:16:55.214] Build status: STARTED
[22:16:55.214] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:18:0) after 8m25s
[22:25:20.239] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:18:0)
[22:25:20.253] Build status: STARTED
[22:25:20.253] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:19:0) after 6m49s
[22:32:09.270] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:19:0)
[22:32:09.368] Build status: STARTED
[22:32:09.368] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:20:0) after 4m44s
[22:36:53.357] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:20:0)
[22:36:53.376] Build status: STARTED
[22:36:53.376] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:21:0) after 5m32s
[22:42:25.402] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:21:0)
[22:42:25.423] Build status: STARTED
[22:42:25.423] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:22:0) after 9m34s
[22:51:59.447] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:22:0)
[22:51:59.463] Build status: STARTED
[22:51:59.463] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:23:0) after 2m53s
[22:54:52.470] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:23:0)
[22:54:52.493] Build status: STARTED
[22:54:52.493] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:24:0) after 6m7s
[23:00:59.613] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:24:0)
[23:00:59.630] Build status: STARTED
[23:00:59.630] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:25:0) after 4m51s
[23:05:50.650] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:25:0)
[23:05:50.665] Build status: STARTED
[23:05:50.666] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:26:0) after 3m47s
[23:09:38.267] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:26:0)
[23:09:38.283] Build status: STARTED
[23:09:38.284] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:27:0) after 3m5s
[23:12:43.318] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:27:0)
[23:12:43.335] Build status: STARTED
[23:12:43.336] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:28:0) after 1m43s
[23:14:26.411] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:28:0)
[23:14:26.427] Build status: STARTED
[23:14:26.428] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:29:0) after 7m36s
[23:22:02.449] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:29:0)
[23:22:02.465] Build status: STARTED
[23:22:02.465] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:30:0) after 6m37s
[23:28:39.479] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:30:0)
[23:28:39.495] Build status: STARTED
[23:28:39.495] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:31:0) after 5m10s
[23:33:49.527] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:31:0)
[23:33:49.542] Build status: STARTED
[23:33:49.542] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:32:0) after 6m44s
[23:40:33.567] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:32:0)
[23:40:34.866] Build status: STARTED
[23:40:34.866] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:33:0) after 5m4s
[23:45:38.886] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:33:0)
[23:45:38.905] Build status: STARTED
[23:45:38.905] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:34:0) after 9m6s
[23:54:44.938] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:34:0)
[23:54:44.955] Build status: STARTED
[23:54:44.955] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:35:0) after 6m9s
[00:00:54.023] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:35:0)
[00:00:54.044] Build status: STARTED
[00:00:54.044] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:36:0) after 5m20s
[00:06:14.173] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:36:0)
[00:06:14.215] Build status: STARTED
[00:06:14.215] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:37:0) after 6m57s
[00:13:11.285] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:37:0)
[00:13:11.302] Build status: STARTED
[00:13:11.302] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:38:0) after 3m17s
[00:16:28.330] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:38:0)
[00:16:28.347] Build status: STARTED
[00:16:28.347] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8882104915927000032:39:0) after 8m36s
[00:18:47.636] Received PubSub notification, asking Buildbucket for the build status
[00:18:47.718] Build:
{
"id": "8716674853910453329",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"number": 156,
"createdBy": "project:chromium-m136",
"createTime": "2025-04-24T20:54:49.569664527Z",
"startTime": "2025-04-24T20:56:19.305538596Z",
"endTime": "2025-04-25T00:18:47.297616724Z",
"updateTime": "2025-04-25T00:18:47.297616724Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "cf2466b14a17f40b9945b3a1f73f7f2081ebba2b",
"ref": "refs/branch-heads/7103"
}
}
}
[00:18:47.718] Invocation finished in 3h23m59.455988654s with status SUCCEEDED