[12:17:06.496] New invocation is queued and will start shortly
[12:17:07.577] Starting the invocation (attempt 1)
[12:17:07.603] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a96bf5e206fa8f122140d68a0764bfd413d5a956"
[12:17:07.604] Popped gitiles commit info from properties and tags
[12:17:07.604] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:17:07.604] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:17:07.604] Buildbucket request:
{
"requestId": "8866283017440815072",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8866283017440815072",
"job": "chromium-m142/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "710ea761563daaf27380e8a40d2e1abc197558d9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@710ea761563daaf27380e8a40d2e1abc197558d9",
"title": "710ea761563daaf27380e8a40d2e1abc197558d9",
"url": "https://chromium.googlesource.com/chromium/src/+/710ea761563daaf27380e8a40d2e1abc197558d9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6b7329796c1118616ef337cdad6013bdd3e98f3e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@6b7329796c1118616ef337cdad6013bdd3e98f3e",
"title": "6b7329796c1118616ef337cdad6013bdd3e98f3e",
"url": "https://chromium.googlesource.com/chromium/src/+/6b7329796c1118616ef337cdad6013bdd3e98f3e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "336431b3627b8922df49dbbd9f118d39dd1b3444"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@336431b3627b8922df49dbbd9f118d39dd1b3444",
"title": "336431b3627b8922df49dbbd9f118d39dd1b3444",
"url": "https://chromium.googlesource.com/chromium/src/+/336431b3627b8922df49dbbd9f118d39dd1b3444"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "29a72700e1d9a26624210ede90de17370ce1b503"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@29a72700e1d9a26624210ede90de17370ce1b503",
"title": "29a72700e1d9a26624210ede90de17370ce1b503",
"url": "https://chromium.googlesource.com/chromium/src/+/29a72700e1d9a26624210ede90de17370ce1b503"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "99453477235c6b89a5c280318a558ac176625d73"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@99453477235c6b89a5c280318a558ac176625d73",
"title": "99453477235c6b89a5c280318a558ac176625d73",
"url": "https://chromium.googlesource.com/chromium/src/+/99453477235c6b89a5c280318a558ac176625d73"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b169c0df367f706315a329c1be6b5b5776327e06"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@b169c0df367f706315a329c1be6b5b5776327e06",
"title": "b169c0df367f706315a329c1be6b5b5776327e06",
"url": "https://chromium.googlesource.com/chromium/src/+/b169c0df367f706315a329c1be6b5b5776327e06"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4d25b5e11b9f46d0c88073d3ece9be6edd83fc77"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@4d25b5e11b9f46d0c88073d3ece9be6edd83fc77",
"title": "4d25b5e11b9f46d0c88073d3ece9be6edd83fc77",
"url": "https://chromium.googlesource.com/chromium/src/+/4d25b5e11b9f46d0c88073d3ece9be6edd83fc77"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a96bf5e206fa8f122140d68a0764bfd413d5a956"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@a96bf5e206fa8f122140d68a0764bfd413d5a956",
"title": "a96bf5e206fa8f122140d68a0764bfd413d5a956",
"url": "https://chromium.googlesource.com/chromium/src/+/a96bf5e206fa8f122140d68a0764bfd413d5a956"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a96bf5e206fa8f122140d68a0764bfd413d5a956",
"ref": "refs/branch-heads/7444"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8866283017440815072"
},
{
"key": "scheduler_job_id",
"value": "chromium-m142/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:17:08.113] Scheduled build:
{
"id": "8700852955567116065",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "win-official"
},
"number": 137,
"createdBy": "project:chromium-m142",
"createTime": "2025-10-16T12:17:07.656915454Z",
"updateTime": "2025-10-16T12:17:07.656915454Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a96bf5e206fa8f122140d68a0764bfd413d5a956",
"ref": "refs/branch-heads/7444"
}
}
}
[12:17:08.113] Task URL: https://cr-buildbucket.appspot.com/build/8700852955567116065
[12:17:08.113] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:2:0) after 3m3s
[12:17:09.087] Received PubSub notification, asking Buildbucket for the build status
[12:17:09.116] Build status: SCHEDULED
[12:17:12.152] Received PubSub notification, asking Buildbucket for the build status
[12:17:12.255] Build status: STARTED
[12:20:11.130] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:2:0)
[12:20:11.152] Build status: STARTED
[12:20:11.152] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:5:0) after 4m9s
[12:24:20.216] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:5:0)
[12:24:20.237] Build status: STARTED
[12:24:20.237] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:6:0) after 8m26s
[12:32:46.300] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:6:0)
[12:32:46.335] Build status: STARTED
[12:32:46.335] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:7:0) after 6m21s
[12:39:07.426] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:7:0)
[12:39:07.648] Build status: STARTED
[12:39:07.648] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:8:0) after 5m56s
[12:45:03.699] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:8:0)
[12:45:03.747] Build status: STARTED
[12:45:03.747] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:9:0) after 7m58s
[12:53:01.794] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:9:0)
[12:53:01.817] Build status: STARTED
[12:53:01.817] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:10:0) after 3m41s
[12:56:42.831] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:10:0)
[12:56:42.846] Build status: STARTED
[12:56:42.846] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:11:0) after 7m30s
[13:04:12.861] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:11:0)
[13:04:12.878] Build status: STARTED
[13:04:12.878] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:12:0) after 6m31s
[13:10:43.896] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:12:0)
[13:10:43.915] Build status: STARTED
[13:10:43.915] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:13:0) after 7m15s
[13:17:58.952] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:13:0)
[13:17:58.983] Build status: STARTED
[13:17:58.983] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:14:0) after 3m15s
[13:21:14.000] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:14:0)
[13:21:14.019] Build status: STARTED
[13:21:14.019] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:15:0) after 4m45s
[13:25:59.083] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:15:0)
[13:25:59.101] Build status: STARTED
[13:25:59.101] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:16:0) after 2m38s
[13:28:37.117] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:16:0)
[13:28:37.175] Build status: STARTED
[13:28:37.175] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:17:0) after 5m35s
[13:34:12.146] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:17:0)
[13:34:12.177] Build status: STARTED
[13:34:12.177] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:18:0) after 2m18s
[13:36:30.175] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:18:0)
[13:36:30.196] Build status: STARTED
[13:36:30.196] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:19:0) after 8m31s
[13:45:01.307] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:19:0)
[13:45:01.326] Build status: STARTED
[13:45:01.326] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:20:0) after 2m14s
[13:47:15.392] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:20:0)
[13:47:15.484] Build status: STARTED
[13:47:15.484] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:21:0) after 3m42s
[13:50:57.591] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:21:0)
[13:50:57.610] Build status: STARTED
[13:50:57.610] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:22:0) after 1m39s
[13:52:36.657] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:22:0)
[13:52:36.674] Build status: STARTED
[13:52:36.674] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:23:0) after 8m56s
[14:01:32.687] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:23:0)
[14:01:32.711] Build status: STARTED
[14:01:32.711] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:24:0) after 3m19s
[14:04:51.754] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:24:0)
[14:04:51.780] Build status: STARTED
[14:04:51.780] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:25:0) after 8m2s
[14:12:53.795] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:25:0)
[14:12:53.818] Build status: STARTED
[14:12:53.818] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:26:0) after 1m9s
[14:14:02.908] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:26:0)
[14:14:02.933] Build status: STARTED
[14:14:02.933] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:27:0) after 6m54s
[14:20:56.948] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:27:0)
[14:20:56.964] Build status: STARTED
[14:20:56.964] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:28:0) after 3m2s
[14:23:58.962] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:28:0)
[14:23:58.980] Build status: STARTED
[14:23:58.980] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:29:0) after 2m31s
[14:26:29.995] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:29:0)
[14:26:30.100] Build status: STARTED
[14:26:30.101] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:30:0) after 4m10s
[14:30:40.114] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:30:0)
[14:30:40.141] Build status: STARTED
[14:30:40.143] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:31:0) after 3m1s
[14:33:41.211] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:31:0)
[14:33:41.230] Build status: STARTED
[14:33:41.230] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:32:0) after 7m16s
[14:40:57.248] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:32:0)
[14:40:57.289] Build status: STARTED
[14:40:57.289] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:33:0) after 7m26s
[14:48:23.404] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:33:0)
[14:48:23.425] Build status: STARTED
[14:48:23.425] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:34:0) after 3m21s
[14:51:44.395] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:34:0)
[14:51:44.414] Build status: STARTED
[14:51:44.414] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8866283017440815072:35:0) after 6m55s
[14:57:21.418] Received PubSub notification, asking Buildbucket for the build status
[14:57:21.447] Build:
{
"id": "8700852955567116065",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "win-official"
},
"number": 137,
"createdBy": "project:chromium-m142",
"createTime": "2025-10-16T12:17:07.656915454Z",
"startTime": "2025-10-16T12:17:11.934727810Z",
"endTime": "2025-10-16T14:57:21.177197218Z",
"updateTime": "2025-10-16T14:57:21.177197218Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a96bf5e206fa8f122140d68a0764bfd413d5a956",
"ref": "refs/branch-heads/7444"
}
}
}
[14:57:21.447] Invocation finished in 2h40m14.962474097s with status SUCCEEDED