[11:37:46.750] New invocation is queued and will start shortly
[11:37:47.828] Starting the invocation (attempt 1)
[11:37:47.859] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b58421b88ec7713746e0b4e0274a1752b6bbc0f1"
[11:37:47.859] Popped gitiles commit info from properties and tags
[11:37:47.860] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:37:47.861] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:37:47.861] Buildbucket request:
{
"requestId": "8876160561152472112",
"builder": {
"project": "chromium-m139",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8876160561152472112",
"job": "chromium-m139/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d6e198f12eca6434c1b049594862b82aeda66f56"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@d6e198f12eca6434c1b049594862b82aeda66f56",
"title": "d6e198f12eca6434c1b049594862b82aeda66f56",
"url": "https://chromium.googlesource.com/chromium/src/+/d6e198f12eca6434c1b049594862b82aeda66f56"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b9736c81ac3f8f43b65bbd0d980f87837a93c744"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@b9736c81ac3f8f43b65bbd0d980f87837a93c744",
"title": "b9736c81ac3f8f43b65bbd0d980f87837a93c744",
"url": "https://chromium.googlesource.com/chromium/src/+/b9736c81ac3f8f43b65bbd0d980f87837a93c744"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0a9168b9fe5be296492c64c71b748a6d7af2a037"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@0a9168b9fe5be296492c64c71b748a6d7af2a037",
"title": "0a9168b9fe5be296492c64c71b748a6d7af2a037",
"url": "https://chromium.googlesource.com/chromium/src/+/0a9168b9fe5be296492c64c71b748a6d7af2a037"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "87b10e8916d2a213f4970d6d28f0c7f97b58573c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@87b10e8916d2a213f4970d6d28f0c7f97b58573c",
"title": "87b10e8916d2a213f4970d6d28f0c7f97b58573c",
"url": "https://chromium.googlesource.com/chromium/src/+/87b10e8916d2a213f4970d6d28f0c7f97b58573c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3abd12a000037beb3d27826f7f85d5f701cffb45"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@3abd12a000037beb3d27826f7f85d5f701cffb45",
"title": "3abd12a000037beb3d27826f7f85d5f701cffb45",
"url": "https://chromium.googlesource.com/chromium/src/+/3abd12a000037beb3d27826f7f85d5f701cffb45"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "66b2dc8fd897ecaa7495aa8a03089c8c2a50d3c4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@66b2dc8fd897ecaa7495aa8a03089c8c2a50d3c4",
"title": "66b2dc8fd897ecaa7495aa8a03089c8c2a50d3c4",
"url": "https://chromium.googlesource.com/chromium/src/+/66b2dc8fd897ecaa7495aa8a03089c8c2a50d3c4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b58421b88ec7713746e0b4e0274a1752b6bbc0f1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@b58421b88ec7713746e0b4e0274a1752b6bbc0f1",
"title": "b58421b88ec7713746e0b4e0274a1752b6bbc0f1",
"url": "https://chromium.googlesource.com/chromium/src/+/b58421b88ec7713746e0b4e0274a1752b6bbc0f1"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b58421b88ec7713746e0b4e0274a1752b6bbc0f1",
"ref": "refs/branch-heads/7258"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8876160561152472112"
},
{
"key": "scheduler_job_id",
"value": "chromium-m139/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:37:48.336] Scheduled build:
{
"id": "8710730499262959633",
"builder": {
"project": "chromium-m139",
"bucket": "ci",
"builder": "win-official"
},
"number": 44,
"createdBy": "project:chromium-m139",
"createTime": "2025-06-29T11:37:47.924788218Z",
"updateTime": "2025-06-29T11:37:47.924788218Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b58421b88ec7713746e0b4e0274a1752b6bbc0f1",
"ref": "refs/branch-heads/7258"
}
}
}
[11:37:48.336] Task URL: https://cr-buildbucket.appspot.com/build/8710730499262959633
[11:37:48.336] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:2:0) after 2m46s
[11:37:49.329] Received PubSub notification, asking Buildbucket for the build status
[11:37:49.351] Build status: SCHEDULED
[11:37:52.039] Received PubSub notification, asking Buildbucket for the build status
[11:37:52.074] Build status: STARTED
[11:40:34.756] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:2:0)
[11:40:34.779] Build status: STARTED
[11:40:34.779] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:5:0) after 4m32s
[11:45:06.945] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:5:0)
[11:45:06.974] Build status: STARTED
[11:45:06.974] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:6:0) after 7m32s
[11:52:39.812] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:6:0)
[11:52:39.882] Build status: STARTED
[11:52:39.882] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:7:0) after 7m38s
[12:00:17.902] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:7:0)
[12:00:17.921] Build status: STARTED
[12:00:17.921] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:8:0) after 8m11s
[12:08:29.009] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:8:0)
[12:08:29.037] Build status: STARTED
[12:08:29.037] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:9:0) after 7m18s
[12:15:47.140] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:9:0)
[12:15:47.157] Build status: STARTED
[12:15:47.157] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:10:0) after 7m18s
[12:23:05.176] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:10:0)
[12:23:05.196] Build status: STARTED
[12:23:05.196] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:11:0) after 6m54s
[12:29:59.672] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:11:0)
[12:29:59.745] Build status: STARTED
[12:29:59.745] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:12:0) after 3m38s
[12:33:37.760] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:12:0)
[12:33:37.803] Build status: STARTED
[12:33:37.803] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:13:0) after 7m42s
[12:41:19.964] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:13:0)
[12:41:19.992] Build status: STARTED
[12:41:19.992] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:14:0) after 5m27s
[12:46:47.148] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:14:0)
[12:46:47.202] Build status: STARTED
[12:46:47.202] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:15:0) after 9m54s
[12:56:41.217] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:15:0)
[12:56:41.247] Build status: STARTED
[12:56:41.247] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:16:0) after 9m4s
[13:05:45.386] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:16:0)
[13:05:45.426] Build status: STARTED
[13:05:45.426] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:17:0) after 5m56s
[13:11:41.497] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:17:0)
[13:11:41.513] Build status: STARTED
[13:11:41.513] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:18:0) after 1m34s
[13:13:15.564] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:18:0)
[13:13:15.588] Build status: STARTED
[13:13:15.588] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:19:0) after 9m50s
[13:23:05.665] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:19:0)
[13:23:05.684] Build status: STARTED
[13:23:05.684] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:20:0) after 1m10s
[13:24:15.705] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:20:0)
[13:24:15.726] Build status: STARTED
[13:24:15.726] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:21:0) after 2m30s
[13:26:45.752] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:21:0)
[13:26:45.769] Build status: STARTED
[13:26:45.770] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:22:0) after 3m51s
[13:30:36.792] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:22:0)
[13:30:36.828] Build status: STARTED
[13:30:36.828] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:23:0) after 4m0s
[13:34:36.868] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:23:0)
[13:34:36.910] Build status: STARTED
[13:34:36.910] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:24:0) after 4m36s
[13:39:12.947] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:24:0)
[13:39:12.965] Build status: STARTED
[13:39:12.965] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:25:0) after 8m46s
[13:47:59.087] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:25:0)
[13:47:59.124] Build status: STARTED
[13:47:59.124] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:26:0) after 7m29s
[13:55:28.218] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:26:0)
[13:55:28.251] Build status: STARTED
[13:55:28.251] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:27:0) after 4m24s
[13:59:52.277] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:27:0)
[13:59:52.294] Build status: STARTED
[13:59:52.294] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:28:0) after 7m5s
[14:06:57.412] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:28:0)
[14:06:57.436] Build status: STARTED
[14:06:57.436] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:29:0) after 5m55s
[14:12:52.458] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:29:0)
[14:12:52.479] Build status: STARTED
[14:12:52.479] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:30:0) after 4m44s
[14:17:36.513] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:30:0)
[14:17:36.528] Build status: STARTED
[14:17:36.528] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:31:0) after 8m13s
[14:25:49.613] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:31:0)
[14:25:49.637] Build status: STARTED
[14:25:49.637] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:32:0) after 3m35s
[14:29:24.716] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:32:0)
[14:29:24.744] Build status: STARTED
[14:29:24.744] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:33:0) after 3m39s
[14:33:03.767] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:33:0)
[14:33:03.864] Build status: STARTED
[14:33:03.864] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:34:0) after 4m8s
[14:37:11.884] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:34:0)
[14:37:11.902] Build status: STARTED
[14:37:11.902] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:35:0) after 5m37s
[14:42:48.969] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:35:0)
[14:42:49.030] Build status: STARTED
[14:42:49.031] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8876160561152472112:36:0) after 4m8s
[14:45:05.040] Received PubSub notification, asking Buildbucket for the build status
[14:45:05.130] Build:
{
"id": "8710730499262959633",
"builder": {
"project": "chromium-m139",
"bucket": "ci",
"builder": "win-official"
},
"number": 44,
"createdBy": "project:chromium-m139",
"createTime": "2025-06-29T11:37:47.924788218Z",
"startTime": "2025-06-29T11:37:51.842875924Z",
"endTime": "2025-06-29T14:44:56.655481032Z",
"updateTime": "2025-06-29T14:44:56.655481032Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b58421b88ec7713746e0b4e0274a1752b6bbc0f1",
"ref": "refs/branch-heads/7258"
}
}
}
[14:45:05.130] Invocation finished in 3h7m18.391539212s with status SUCCEEDED