[17:56:19.722] New invocation is queued and will start shortly
[17:56:20.804] Starting the invocation (attempt 1)
[17:56:20.828] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/779386888e9bc9793c4b8a06333de602c2675228"
[17:56:20.828] Popped gitiles commit info from properties and tags
[17:56:20.828] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:56:20.828] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:56:20.828] Buildbucket request:
{
"requestId": "8876952117567915552",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8876952117567915552",
"job": "chromium-m137/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e3c26f384cc307798abad31185ffbbf40f40f0e5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@e3c26f384cc307798abad31185ffbbf40f40f0e5",
"title": "e3c26f384cc307798abad31185ffbbf40f40f0e5",
"url": "https://chromium.googlesource.com/chromium/src/+/e3c26f384cc307798abad31185ffbbf40f40f0e5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bf8314f5faa1a9483f45ba32bdd2643f0ce2577c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@bf8314f5faa1a9483f45ba32bdd2643f0ce2577c",
"title": "bf8314f5faa1a9483f45ba32bdd2643f0ce2577c",
"url": "https://chromium.googlesource.com/chromium/src/+/bf8314f5faa1a9483f45ba32bdd2643f0ce2577c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "aa5434931b1bc61e581a62a5d34c91cf2cb61a98"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@aa5434931b1bc61e581a62a5d34c91cf2cb61a98",
"title": "aa5434931b1bc61e581a62a5d34c91cf2cb61a98",
"url": "https://chromium.googlesource.com/chromium/src/+/aa5434931b1bc61e581a62a5d34c91cf2cb61a98"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1cdebf4644d0c6adb20da0e17868c0427b3a31c1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@1cdebf4644d0c6adb20da0e17868c0427b3a31c1",
"title": "1cdebf4644d0c6adb20da0e17868c0427b3a31c1",
"url": "https://chromium.googlesource.com/chromium/src/+/1cdebf4644d0c6adb20da0e17868c0427b3a31c1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ed7aa9c22cfcf011169613e1c299f496c5c1866b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@ed7aa9c22cfcf011169613e1c299f496c5c1866b",
"title": "ed7aa9c22cfcf011169613e1c299f496c5c1866b",
"url": "https://chromium.googlesource.com/chromium/src/+/ed7aa9c22cfcf011169613e1c299f496c5c1866b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "779386888e9bc9793c4b8a06333de602c2675228"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@779386888e9bc9793c4b8a06333de602c2675228",
"title": "779386888e9bc9793c4b8a06333de602c2675228",
"url": "https://chromium.googlesource.com/chromium/src/+/779386888e9bc9793c4b8a06333de602c2675228"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "779386888e9bc9793c4b8a06333de602c2675228",
"ref": "refs/branch-heads/7151"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8876952117567915552"
},
{
"key": "scheduler_job_id",
"value": "chromium-m137/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:56:21.329] Scheduled build:
{
"id": "8711522055681455905",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "win-official"
},
"number": 396,
"createdBy": "project:chromium-m137",
"createTime": "2025-06-20T17:56:20.898411713Z",
"updateTime": "2025-06-20T17:56:20.898411713Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "779386888e9bc9793c4b8a06333de602c2675228",
"ref": "refs/branch-heads/7151"
}
}
}
[17:56:21.329] Task URL: https://cr-buildbucket.appspot.com/build/8711522055681455905
[17:56:21.329] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:2:0) after 3m31s
[17:56:22.340] Received PubSub notification, asking Buildbucket for the build status
[17:56:22.372] Build status: SCHEDULED
[17:57:43.384] Received PubSub notification, asking Buildbucket for the build status
[17:57:43.407] Build status: STARTED
[17:59:52.358] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:2:0)
[17:59:52.388] Build status: STARTED
[17:59:52.388] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:5:0) after 2m58s
[18:02:50.408] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:5:0)
[18:02:50.439] Build status: STARTED
[18:02:50.439] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:6:0) after 2m26s
[18:05:16.444] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:6:0)
[18:05:16.464] Build status: STARTED
[18:05:16.465] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:7:0) after 3m38s
[18:08:54.484] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:7:0)
[18:08:54.506] Build status: STARTED
[18:08:54.506] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:8:0) after 5m45s
[18:14:39.544] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:8:0)
[18:14:39.586] Build status: STARTED
[18:14:39.586] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:9:0) after 8m6s
[18:22:45.612] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:9:0)
[18:22:45.640] Build status: STARTED
[18:22:45.640] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:10:0) after 2m33s
[18:25:18.668] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:10:0)
[18:25:18.732] Build status: STARTED
[18:25:18.732] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:11:0) after 8m32s
[18:33:50.746] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:11:0)
[18:33:50.789] Build status: STARTED
[18:33:50.790] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:12:0) after 9m55s
[18:43:45.871] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:12:0)
[18:43:47.048] Build status: STARTED
[18:43:47.048] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:13:0) after 9m18s
[18:53:05.222] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:13:0)
[18:53:05.513] Build status: STARTED
[18:53:05.513] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:14:0) after 9m7s
[19:02:12.539] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:14:0)
[19:02:12.562] Build status: STARTED
[19:02:12.562] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:15:0) after 3m17s
[19:05:29.575] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:15:0)
[19:05:29.686] Build status: STARTED
[19:05:29.686] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:16:0) after 6m55s
[19:12:24.718] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:16:0)
[19:12:24.751] Build status: STARTED
[19:12:24.751] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:17:0) after 3m51s
[19:16:15.817] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:17:0)
[19:16:15.838] Build status: STARTED
[19:16:15.838] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:18:0) after 8m59s
[19:25:14.899] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:18:0)
[19:25:14.920] Build status: STARTED
[19:25:14.920] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:19:0) after 2m16s
[19:27:30.953] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:19:0)
[19:27:30.973] Build status: STARTED
[19:27:30.973] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:20:0) after 6m6s
[19:33:36.995] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:20:0)
[19:33:37.023] Build status: STARTED
[19:33:37.023] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:21:0) after 3m1s
[19:36:38.005] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:21:0)
[19:36:38.025] Build status: STARTED
[19:36:38.025] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:22:0) after 4m57s
[19:41:35.050] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:22:0)
[19:41:35.071] Build status: STARTED
[19:41:35.071] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:23:0) after 5m4s
[19:46:39.099] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:23:0)
[19:46:39.119] Build status: STARTED
[19:46:39.119] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:24:0) after 6m50s
[19:53:29.189] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:24:0)
[19:53:29.209] Build status: STARTED
[19:53:29.209] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:25:0) after 9m20s
[20:02:49.236] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:25:0)
[20:02:49.263] Build status: STARTED
[20:02:49.263] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:26:0) after 5m20s
[20:08:09.286] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:26:0)
[20:08:09.309] Build status: STARTED
[20:08:09.309] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:27:0) after 5m11s
[20:13:20.330] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:27:0)
[20:13:20.358] Build status: STARTED
[20:13:20.358] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:28:0) after 7m16s
[20:20:36.419] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:28:0)
[20:20:36.444] Build status: STARTED
[20:20:36.444] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:29:0) after 2m48s
[20:23:24.433] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:29:0)
[20:23:24.449] Build status: STARTED
[20:23:24.449] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:30:0) after 5m43s
[20:29:07.481] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:30:0)
[20:29:07.567] Build status: STARTED
[20:29:07.567] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:31:0) after 4m45s
[20:33:52.632] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:31:0)
[20:33:52.666] Build status: STARTED
[20:33:52.666] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:32:0) after 4m59s
[20:38:51.672] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:32:0)
[20:38:51.691] Build status: STARTED
[20:38:51.691] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:33:0) after 6m29s
[20:45:20.784] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:33:0)
[20:45:20.814] Build status: STARTED
[20:45:20.814] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:34:0) after 5m38s
[20:50:58.846] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:34:0)
[20:50:58.866] Build status: STARTED
[20:50:58.866] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:35:0) after 8m3s
[20:59:01.886] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:35:0)
[20:59:01.906] Build status: STARTED
[20:59:01.906] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:36:0) after 1m7s
[21:00:08.925] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:36:0)
[21:00:08.941] Build status: STARTED
[21:00:08.941] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:37:0) after 3m23s
[21:03:32.004] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:37:0)
[21:03:32.029] Build status: STARTED
[21:03:32.029] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:38:0) after 1m37s
[21:05:09.046] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:38:0)
[21:05:09.082] Build status: STARTED
[21:05:09.082] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:39:0) after 2m1s
[21:07:10.158] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:39:0)
[21:07:10.181] Build status: STARTED
[21:07:10.181] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8876952117567915552:40:0) after 5m21s
[21:07:12.668] Received PubSub notification, asking Buildbucket for the build status
[21:07:12.960] Build:
{
"id": "8711522055681455905",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "win-official"
},
"number": 396,
"createdBy": "project:chromium-m137",
"createTime": "2025-06-20T17:56:20.898411713Z",
"startTime": "2025-06-20T17:57:43.137178221Z",
"endTime": "2025-06-20T21:07:11.671782308Z",
"updateTime": "2025-06-20T21:07:11.671782308Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "779386888e9bc9793c4b8a06333de602c2675228",
"ref": "refs/branch-heads/7151"
}
}
}
[21:07:12.960] Invocation finished in 3h10m53.244776156s with status SUCCEEDED