[03:05:36.836] New invocation is queued and will start shortly
[03:05:38.288] Starting the invocation (attempt 1)
[03:05:38.320] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3d128105aecf32bc3a4a90a47932d3f9d69afa98"
[03:05:38.320] Popped gitiles commit info from properties and tags
[03:05:38.320] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:05:38.320] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:05:38.320] Buildbucket request:
{
"requestId": "8843215488025315376",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8843215488025315376",
"job": "chromium-m149/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e938594fb20ceeb98feb087ea0354a90db3071ae"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@e938594fb20ceeb98feb087ea0354a90db3071ae",
"title": "e938594fb20ceeb98feb087ea0354a90db3071ae",
"url": "https://chromium.googlesource.com/chromium/src/+/e938594fb20ceeb98feb087ea0354a90db3071ae"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d56266934e24109b5fda41d6e24b5cfcdbf85594"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@d56266934e24109b5fda41d6e24b5cfcdbf85594",
"title": "d56266934e24109b5fda41d6e24b5cfcdbf85594",
"url": "https://chromium.googlesource.com/chromium/src/+/d56266934e24109b5fda41d6e24b5cfcdbf85594"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "72480ea794b6524d99ad3bdd89027eb769376a42"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@72480ea794b6524d99ad3bdd89027eb769376a42",
"title": "72480ea794b6524d99ad3bdd89027eb769376a42",
"url": "https://chromium.googlesource.com/chromium/src/+/72480ea794b6524d99ad3bdd89027eb769376a42"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "87b1edecb314294e24fb3d388418f373edbd630e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@87b1edecb314294e24fb3d388418f373edbd630e",
"title": "87b1edecb314294e24fb3d388418f373edbd630e",
"url": "https://chromium.googlesource.com/chromium/src/+/87b1edecb314294e24fb3d388418f373edbd630e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "eda0d7b1224b7c85edbe29fe9c747e44b58be27d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@eda0d7b1224b7c85edbe29fe9c747e44b58be27d",
"title": "eda0d7b1224b7c85edbe29fe9c747e44b58be27d",
"url": "https://chromium.googlesource.com/chromium/src/+/eda0d7b1224b7c85edbe29fe9c747e44b58be27d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5cbe6b715258f13555b9e7b3802af71033e3cda7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@5cbe6b715258f13555b9e7b3802af71033e3cda7",
"title": "5cbe6b715258f13555b9e7b3802af71033e3cda7",
"url": "https://chromium.googlesource.com/chromium/src/+/5cbe6b715258f13555b9e7b3802af71033e3cda7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3d128105aecf32bc3a4a90a47932d3f9d69afa98"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@3d128105aecf32bc3a4a90a47932d3f9d69afa98",
"title": "3d128105aecf32bc3a4a90a47932d3f9d69afa98",
"url": "https://chromium.googlesource.com/chromium/src/+/3d128105aecf32bc3a4a90a47932d3f9d69afa98"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3d128105aecf32bc3a4a90a47932d3f9d69afa98",
"ref": "refs/branch-heads/7827"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8843215488025315376"
},
{
"key": "scheduler_job_id",
"value": "chromium-m149/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:05:38.816] Scheduled build:
{
"id": "8677785425750181905",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win-official"
},
"number": 395,
"createdBy": "project:chromium-m149",
"createTime": "2026-06-28T03:05:38.385961240Z",
"updateTime": "2026-06-28T03:05:38.385961240Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3d128105aecf32bc3a4a90a47932d3f9d69afa98",
"ref": "refs/branch-heads/7827"
}
}
}
[03:05:38.816] Task URL: https://cr-buildbucket.appspot.com/build/8677785425750181905
[03:05:38.816] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:2:0) after 5m41s
[03:05:39.078] Received PubSub notification, asking Buildbucket for the build status
[03:05:39.274] Build status: SCHEDULED
[03:05:43.199] Received PubSub notification, asking Buildbucket for the build status
[03:05:43.218] Build status: STARTED
[03:11:19.868] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:2:0)
[03:11:19.920] Build status: STARTED
[03:11:19.920] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:5:0) after 2m3s
[03:13:22.934] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:5:0)
[03:13:22.949] Build status: STARTED
[03:13:22.949] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:6:0) after 2m36s
[03:15:59.200] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:6:0)
[03:15:59.222] Build status: STARTED
[03:15:59.222] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:7:0) after 4m25s
[03:20:24.237] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:7:0)
[03:20:24.261] Build status: STARTED
[03:20:24.261] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:8:0) after 9m46s
[03:30:10.333] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:8:0)
[03:30:10.385] Build status: STARTED
[03:30:10.385] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:9:0) after 9m29s
[03:39:39.682] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:9:0)
[03:39:39.737] Build status: STARTED
[03:39:39.737] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:10:0) after 5m12s
[03:44:51.753] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:10:0)
[03:44:51.769] Build status: STARTED
[03:44:51.769] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:11:0) after 3m32s
[03:48:23.816] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:11:0)
[03:48:24.082] Build status: STARTED
[03:48:24.082] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:12:0) after 3m4s
[03:51:28.063] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:12:0)
[03:51:28.086] Build status: STARTED
[03:51:28.086] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:13:0) after 2m54s
[03:54:22.161] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:13:0)
[03:54:22.185] Build status: STARTED
[03:54:22.185] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:14:0) after 6m59s
[04:01:21.280] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:14:0)
[04:01:21.299] Build status: STARTED
[04:01:21.299] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:15:0) after 9m10s
[04:10:31.826] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:15:0)
[04:10:31.853] Build status: STARTED
[04:10:31.853] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:16:0) after 4m49s
[04:15:20.921] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:16:0)
[04:15:20.949] Build status: STARTED
[04:15:20.949] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:17:0) after 7m58s
[04:23:18.963] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:17:0)
[04:23:19.000] Build status: STARTED
[04:23:19.000] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:18:0) after 8m34s
[04:31:52.983] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:18:0)
[04:31:53.003] Build status: STARTED
[04:31:53.003] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:19:0) after 3m5s
[04:34:58.034] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:19:0)
[04:34:58.049] Build status: STARTED
[04:34:58.049] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:20:0) after 6m40s
[04:41:38.114] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:20:0)
[04:41:38.131] Build status: STARTED
[04:41:38.131] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:21:0) after 8m48s
[04:50:26.125] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:21:0)
[04:50:26.148] Build status: STARTED
[04:50:26.148] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:22:0) after 2m15s
[04:52:41.299] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:22:0)
[04:52:41.317] Build status: STARTED
[04:52:41.317] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:23:0) after 7m51s
[05:00:32.334] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:23:0)
[05:00:32.360] Build status: STARTED
[05:00:32.360] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:24:0) after 9m34s
[05:10:06.465] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:24:0)
[05:10:06.484] Build status: STARTED
[05:10:06.484] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:25:0) after 2m47s
[05:12:53.499] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:25:0)
[05:12:53.518] Build status: STARTED
[05:12:53.518] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:26:0) after 8m6s
[05:20:59.592] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:26:0)
[05:20:59.618] Build status: STARTED
[05:20:59.618] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:27:0) after 8m9s
[05:29:08.591] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:27:0)
[05:29:08.614] Build status: STARTED
[05:29:08.614] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:28:0) after 8m7s
[05:37:15.629] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:28:0)
[05:37:15.652] Build status: STARTED
[05:37:15.652] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:29:0) after 6m20s
[05:43:36.280] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:29:0)
[05:43:36.301] Build status: STARTED
[05:43:36.301] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:30:0) after 4m37s
[05:48:13.531] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:30:0)
[05:48:13.581] Build status: STARTED
[05:48:13.581] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:31:0) after 1m52s
[05:50:05.580] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:31:0)
[05:50:05.597] Build status: STARTED
[05:50:05.597] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:32:0) after 1m21s
[05:51:26.612] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:32:0)
[05:51:26.639] Build status: STARTED
[05:51:26.639] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:33:0) after 3m42s
[05:55:08.652] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:33:0)
[05:55:08.672] Build status: STARTED
[05:55:08.672] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:34:0) after 1m25s
[05:56:33.681] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:34:0)
[05:56:33.699] Build status: STARTED
[05:56:33.699] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8843215488025315376:35:0) after 7m28s
[06:02:23.277] Received PubSub notification, asking Buildbucket for the build status
[06:02:23.295] Build:
{
"id": "8677785425750181905",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win-official"
},
"number": 395,
"createdBy": "project:chromium-m149",
"createTime": "2026-06-28T03:05:38.385961240Z",
"startTime": "2026-06-28T03:05:43.014422463Z",
"endTime": "2026-06-28T06:02:22.892380023Z",
"updateTime": "2026-06-28T06:02:22.892380023Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3d128105aecf32bc3a4a90a47932d3f9d69afa98",
"ref": "refs/branch-heads/7827"
}
}
}
[06:02:23.295] Invocation finished in 2h56m46.463273584s with status SUCCEEDED