[11:56:55.317] New invocation is queued and will start shortly
[11:56:56.597] Starting the invocation (attempt 1)
[11:56:56.620] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d07cf5ec9913e21459becb051de9ac9a04479508"
[11:56:56.620] Popped gitiles commit info from properties and tags
[11:56:56.620] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:56:56.620] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:56:56.620] Buildbucket request:
{
"requestId": "8848074297202685568",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8848074297202685568",
"job": "chromium-m148/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "52204feae115990b25b95fed1656d30c9371790d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@52204feae115990b25b95fed1656d30c9371790d",
"title": "52204feae115990b25b95fed1656d30c9371790d",
"url": "https://chromium.googlesource.com/chromium/src/+/52204feae115990b25b95fed1656d30c9371790d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0fae775abbd3032be4b357127faf34f38a358025"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@0fae775abbd3032be4b357127faf34f38a358025",
"title": "0fae775abbd3032be4b357127faf34f38a358025",
"url": "https://chromium.googlesource.com/chromium/src/+/0fae775abbd3032be4b357127faf34f38a358025"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bc582f8cbdb3c3eb3bc2e49f8dce314af084f171"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@bc582f8cbdb3c3eb3bc2e49f8dce314af084f171",
"title": "bc582f8cbdb3c3eb3bc2e49f8dce314af084f171",
"url": "https://chromium.googlesource.com/chromium/src/+/bc582f8cbdb3c3eb3bc2e49f8dce314af084f171"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "36a6855af4d617da87466188d1902be024682be7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@36a6855af4d617da87466188d1902be024682be7",
"title": "36a6855af4d617da87466188d1902be024682be7",
"url": "https://chromium.googlesource.com/chromium/src/+/36a6855af4d617da87466188d1902be024682be7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1646112de4486d533955173cd6e8ae2c110c539b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@1646112de4486d533955173cd6e8ae2c110c539b",
"title": "1646112de4486d533955173cd6e8ae2c110c539b",
"url": "https://chromium.googlesource.com/chromium/src/+/1646112de4486d533955173cd6e8ae2c110c539b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fd85de02ae712f8975577a030c24c42d38461bd6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@fd85de02ae712f8975577a030c24c42d38461bd6",
"title": "fd85de02ae712f8975577a030c24c42d38461bd6",
"url": "https://chromium.googlesource.com/chromium/src/+/fd85de02ae712f8975577a030c24c42d38461bd6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cab30b51401ef64f20c6d716f6943a7659fcde8b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@cab30b51401ef64f20c6d716f6943a7659fcde8b",
"title": "cab30b51401ef64f20c6d716f6943a7659fcde8b",
"url": "https://chromium.googlesource.com/chromium/src/+/cab30b51401ef64f20c6d716f6943a7659fcde8b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "49da23f810b02eedf81877e94525946e80f92609"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@49da23f810b02eedf81877e94525946e80f92609",
"title": "49da23f810b02eedf81877e94525946e80f92609",
"url": "https://chromium.googlesource.com/chromium/src/+/49da23f810b02eedf81877e94525946e80f92609"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d095765d1cc5a941bc0423d40dd0575365577cff"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@d095765d1cc5a941bc0423d40dd0575365577cff",
"title": "d095765d1cc5a941bc0423d40dd0575365577cff",
"url": "https://chromium.googlesource.com/chromium/src/+/d095765d1cc5a941bc0423d40dd0575365577cff"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1ed44595b5223906061718766001c52b5bcc8a2b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@1ed44595b5223906061718766001c52b5bcc8a2b",
"title": "1ed44595b5223906061718766001c52b5bcc8a2b",
"url": "https://chromium.googlesource.com/chromium/src/+/1ed44595b5223906061718766001c52b5bcc8a2b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d07cf5ec9913e21459becb051de9ac9a04479508"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@d07cf5ec9913e21459becb051de9ac9a04479508",
"title": "d07cf5ec9913e21459becb051de9ac9a04479508",
"url": "https://chromium.googlesource.com/chromium/src/+/d07cf5ec9913e21459becb051de9ac9a04479508"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d07cf5ec9913e21459becb051de9ac9a04479508",
"ref": "refs/branch-heads/7778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8848074297202685568"
},
{
"key": "scheduler_job_id",
"value": "chromium-m148/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:56:57.083] Scheduled build:
{
"id": "8682644235131267137",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "win32-official"
},
"number": 157,
"createdBy": "project:chromium-m148",
"createTime": "2026-05-05T11:56:56.670172923Z",
"updateTime": "2026-05-05T11:56:56.670172923Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d07cf5ec9913e21459becb051de9ac9a04479508",
"ref": "refs/branch-heads/7778"
}
}
}
[11:56:57.083] Task URL: https://cr-buildbucket.appspot.com/build/8682644235131267137
[11:56:57.083] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:2:0) after 9m7s
[11:56:58.029] Received PubSub notification, asking Buildbucket for the build status
[11:56:58.044] Build status: SCHEDULED
[12:03:12.112] Received PubSub notification, asking Buildbucket for the build status
[12:03:12.136] Build status: STARTED
[12:06:04.248] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:2:0)
[12:06:04.274] Build status: STARTED
[12:06:04.274] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:5:0) after 5m15s
[12:11:19.379] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:5:0)
[12:11:19.404] Build status: STARTED
[12:11:19.404] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:6:0) after 6m11s
[12:17:30.467] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:6:0)
[12:17:30.487] Build status: STARTED
[12:17:30.487] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:7:0) after 8m10s
[12:25:40.553] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:7:0)
[12:25:40.590] Build status: STARTED
[12:25:40.590] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:8:0) after 9m2s
[12:34:42.571] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:8:0)
[12:34:42.597] Build status: STARTED
[12:34:42.597] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:9:0) after 7m7s
[12:41:49.566] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:9:0)
[12:41:49.581] Build status: STARTED
[12:41:49.581] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:10:0) after 9m48s
[12:51:37.691] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:10:0)
[12:51:37.710] Build status: STARTED
[12:51:37.710] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:11:0) after 4m39s
[12:56:16.708] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:11:0)
[12:56:16.729] Build status: STARTED
[12:56:16.729] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:12:0) after 5m59s
[13:02:15.813] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:12:0)
[13:02:15.841] Build status: STARTED
[13:02:15.841] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:13:0) after 9m45s
[13:12:00.948] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:13:0)
[13:12:00.997] Build status: STARTED
[13:12:00.997] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:14:0) after 4m45s
[13:16:46.199] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:14:0)
[13:16:46.213] Build status: STARTED
[13:16:46.213] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:15:0) after 6m48s
[13:23:34.299] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:15:0)
[13:23:34.339] Build status: STARTED
[13:23:34.339] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:16:0) after 6m30s
[13:30:04.415] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:16:0)
[13:30:04.433] Build status: STARTED
[13:30:04.497] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:17:0) after 8m14s
[13:38:18.596] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:17:0)
[13:38:18.615] Build status: STARTED
[13:38:18.615] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:18:0) after 8m48s
[13:47:06.635] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:18:0)
[13:47:06.649] Build status: STARTED
[13:47:06.649] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:19:0) after 7m12s
[13:54:18.635] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:19:0)
[13:54:18.684] Build status: STARTED
[13:54:18.684] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:20:0) after 9m1s
[14:03:19.703] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:20:0)
[14:03:19.723] Build status: STARTED
[14:03:19.723] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:21:0) after 5m45s
[14:09:04.791] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:21:0)
[14:09:04.820] Build status: STARTED
[14:09:04.820] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:22:0) after 9m20s
[14:18:25.152] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:22:0)
[14:18:25.168] Build status: STARTED
[14:18:25.168] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:23:0) after 2m58s
[14:21:23.415] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:23:0)
[14:21:23.440] Build status: STARTED
[14:21:23.440] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:24:0) after 9m21s
[14:30:45.061] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:24:0)
[14:30:45.089] Build status: STARTED
[14:30:45.089] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:25:0) after 1m28s
[14:32:13.294] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:25:0)
[14:32:13.312] Build status: STARTED
[14:32:13.312] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:26:0) after 7m41s
[14:39:54.332] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:26:0)
[14:39:54.353] Build status: STARTED
[14:39:54.353] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:27:0) after 8m6s
[14:48:00.327] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:27:0)
[14:48:00.366] Build status: STARTED
[14:48:00.366] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:28:0) after 9m12s
[14:57:12.452] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:28:0)
[14:57:12.499] Build status: STARTED
[14:57:12.500] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:29:0) after 2m48s
[15:00:00.522] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:29:0)
[15:00:00.543] Build status: STARTED
[15:00:00.543] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:30:0) after 1m53s
[15:01:53.544] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:30:0)
[15:01:53.581] Build status: STARTED
[15:01:53.581] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:31:0) after 3m34s
[15:05:27.721] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:31:0)
[15:05:27.750] Build status: STARTED
[15:05:27.750] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:32:0) after 3m21s
[15:08:48.761] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:32:0)
[15:08:48.786] Build status: STARTED
[15:08:48.786] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:33:0) after 7m25s
[15:16:13.936] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:33:0)
[15:16:13.981] Build status: STARTED
[15:16:13.981] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:34:0) after 9m1s
[15:25:15.068] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:34:0)
[15:25:15.117] Build status: STARTED
[15:25:15.117] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:35:0) after 5m16s
[15:30:31.130] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:35:0)
[15:30:31.279] Build status: STARTED
[15:30:31.279] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:36:0) after 7m29s
[15:38:00.368] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:36:0)
[15:38:00.388] Build status: STARTED
[15:38:00.388] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:37:0) after 9m25s
[15:47:25.509] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:37:0)
[15:47:25.538] Build status: STARTED
[15:47:25.538] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:38:0) after 5m30s
[15:52:55.580] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:38:0)
[15:52:55.599] Build status: STARTED
[15:52:55.599] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:39:0) after 3m45s
[15:56:40.882] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:39:0)
[15:56:40.904] Build status: STARTED
[15:56:40.904] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:40:0) after 3m25s
[16:00:06.091] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:40:0)
[16:00:06.111] Build status: STARTED
[16:00:06.111] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:41:0) after 1m28s
[16:01:34.309] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:41:0)
[16:01:34.325] Build status: STARTED
[16:01:34.325] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:42:0) after 8m52s
[16:10:26.344] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:42:0)
[16:10:26.370] Build status: STARTED
[16:10:26.370] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:43:0) after 1m44s
[16:12:10.451] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:43:0)
[16:12:10.485] Build status: STARTED
[16:12:10.486] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:44:0) after 7m47s
[16:19:57.810] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:44:0)
[16:19:57.839] Build status: STARTED
[16:19:57.839] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:45:0) after 2m43s
[16:22:40.872] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:45:0)
[16:22:40.895] Build status: STARTED
[16:22:40.895] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:46:0) after 4m48s
[16:27:28.913] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:46:0)
[16:27:28.971] Build status: STARTED
[16:27:28.971] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:47:0) after 6m37s
[16:34:06.154] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:47:0)
[16:34:06.187] Build status: STARTED
[16:34:06.187] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848074297202685568:48:0) after 3m30s
[16:36:50.684] Received PubSub notification, asking Buildbucket for the build status
[16:36:50.699] Build:
{
"id": "8682644235131267137",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "win32-official"
},
"number": 157,
"createdBy": "project:chromium-m148",
"createTime": "2026-05-05T11:56:56.670172923Z",
"startTime": "2026-05-05T12:03:11.686034881Z",
"endTime": "2026-05-05T16:36:50.346094462Z",
"updateTime": "2026-05-05T16:36:50.346094462Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d07cf5ec9913e21459becb051de9ac9a04479508",
"ref": "refs/branch-heads/7778"
}
}
}
[16:36:50.699] Invocation finished in 4h39m55.387347717s with status SUCCEEDED