[19:36:20.167] New invocation is queued and will start shortly
[19:36:21.255] Starting the invocation (attempt 1)
[19:36:21.347] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fd54da84f56c041529a7223222c509ee39ccca04"
[19:36:21.347] Popped gitiles commit info from properties and tags
[19:36:21.347] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:36:21.347] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:36:21.348] Buildbucket request:
{
"requestId": "8886458507154785136",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8886458507154785136",
"job": "chromium-m134/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a77b75c076067d1e8f11ceba7512827a568694fb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@a77b75c076067d1e8f11ceba7512827a568694fb",
"title": "a77b75c076067d1e8f11ceba7512827a568694fb",
"url": "https://chromium.googlesource.com/chromium/src/+/a77b75c076067d1e8f11ceba7512827a568694fb"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9a8a36415785e5ff5cb731cdfeb7a7af21104610"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@9a8a36415785e5ff5cb731cdfeb7a7af21104610",
"title": "9a8a36415785e5ff5cb731cdfeb7a7af21104610",
"url": "https://chromium.googlesource.com/chromium/src/+/9a8a36415785e5ff5cb731cdfeb7a7af21104610"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "924df6824538033aa83c75032d4b8a1ee130cac7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@924df6824538033aa83c75032d4b8a1ee130cac7",
"title": "924df6824538033aa83c75032d4b8a1ee130cac7",
"url": "https://chromium.googlesource.com/chromium/src/+/924df6824538033aa83c75032d4b8a1ee130cac7"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "dbb1991af7babe3cea467f30865e066f7dd617f9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@dbb1991af7babe3cea467f30865e066f7dd617f9",
"title": "dbb1991af7babe3cea467f30865e066f7dd617f9",
"url": "https://chromium.googlesource.com/chromium/src/+/dbb1991af7babe3cea467f30865e066f7dd617f9"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8f5f373e4c19d81181285f4d79c2adee39c7bd86"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@8f5f373e4c19d81181285f4d79c2adee39c7bd86",
"title": "8f5f373e4c19d81181285f4d79c2adee39c7bd86",
"url": "https://chromium.googlesource.com/chromium/src/+/8f5f373e4c19d81181285f4d79c2adee39c7bd86"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7cbd994fd98dafb179d33806441a1817ae40c3cd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@7cbd994fd98dafb179d33806441a1817ae40c3cd",
"title": "7cbd994fd98dafb179d33806441a1817ae40c3cd",
"url": "https://chromium.googlesource.com/chromium/src/+/7cbd994fd98dafb179d33806441a1817ae40c3cd"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "efb14de3fe4f2902d1ba5d0d49f50141b84cb0ad"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@efb14de3fe4f2902d1ba5d0d49f50141b84cb0ad",
"title": "efb14de3fe4f2902d1ba5d0d49f50141b84cb0ad",
"url": "https://chromium.googlesource.com/chromium/src/+/efb14de3fe4f2902d1ba5d0d49f50141b84cb0ad"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9120eb438ff65e803bb0810119b22e8b6a71dea3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@9120eb438ff65e803bb0810119b22e8b6a71dea3",
"title": "9120eb438ff65e803bb0810119b22e8b6a71dea3",
"url": "https://chromium.googlesource.com/chromium/src/+/9120eb438ff65e803bb0810119b22e8b6a71dea3"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "83b66174f976edc82f878e5d00e058eeedd27c05"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@83b66174f976edc82f878e5d00e058eeedd27c05",
"title": "83b66174f976edc82f878e5d00e058eeedd27c05",
"url": "https://chromium.googlesource.com/chromium/src/+/83b66174f976edc82f878e5d00e058eeedd27c05"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7ddbb0a6491d5f57ae920b2ff3ea23839fa9650a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@7ddbb0a6491d5f57ae920b2ff3ea23839fa9650a",
"title": "7ddbb0a6491d5f57ae920b2ff3ea23839fa9650a",
"url": "https://chromium.googlesource.com/chromium/src/+/7ddbb0a6491d5f57ae920b2ff3ea23839fa9650a"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9a16ee20d41b0a5b637c4745accd7e1cc4481b58"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@9a16ee20d41b0a5b637c4745accd7e1cc4481b58",
"title": "9a16ee20d41b0a5b637c4745accd7e1cc4481b58",
"url": "https://chromium.googlesource.com/chromium/src/+/9a16ee20d41b0a5b637c4745accd7e1cc4481b58"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4372d46082c6325a054e5d1124842b8075992f20"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@4372d46082c6325a054e5d1124842b8075992f20",
"title": "4372d46082c6325a054e5d1124842b8075992f20",
"url": "https://chromium.googlesource.com/chromium/src/+/4372d46082c6325a054e5d1124842b8075992f20"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ea2f01f3c99d1b273decd52543c66a1bdc0741ec"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@ea2f01f3c99d1b273decd52543c66a1bdc0741ec",
"title": "ea2f01f3c99d1b273decd52543c66a1bdc0741ec",
"url": "https://chromium.googlesource.com/chromium/src/+/ea2f01f3c99d1b273decd52543c66a1bdc0741ec"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "eb5adeec1d2bc0e674a2ec90c4e877da9fddf062"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@eb5adeec1d2bc0e674a2ec90c4e877da9fddf062",
"title": "eb5adeec1d2bc0e674a2ec90c4e877da9fddf062",
"url": "https://chromium.googlesource.com/chromium/src/+/eb5adeec1d2bc0e674a2ec90c4e877da9fddf062"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0e6c9fe5f17fe2757bc360731a0992d091a40c67"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@0e6c9fe5f17fe2757bc360731a0992d091a40c67",
"title": "0e6c9fe5f17fe2757bc360731a0992d091a40c67",
"url": "https://chromium.googlesource.com/chromium/src/+/0e6c9fe5f17fe2757bc360731a0992d091a40c67"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5c0565f6c993d83023cdafa340b8dd8d59a075eb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@5c0565f6c993d83023cdafa340b8dd8d59a075eb",
"title": "5c0565f6c993d83023cdafa340b8dd8d59a075eb",
"url": "https://chromium.googlesource.com/chromium/src/+/5c0565f6c993d83023cdafa340b8dd8d59a075eb"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fd54da84f56c041529a7223222c509ee39ccca04"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@fd54da84f56c041529a7223222c509ee39ccca04",
"title": "fd54da84f56c041529a7223222c509ee39ccca04",
"url": "https://chromium.googlesource.com/chromium/src/+/fd54da84f56c041529a7223222c509ee39ccca04"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fd54da84f56c041529a7223222c509ee39ccca04",
"ref": "refs/branch-heads/6998"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8886458507154785136"
},
{
"key": "scheduler_job_id",
"value": "chromium-m134/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:36:21.944] Scheduled build:
{
"id": "8721028445061787857",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "win32-official"
},
"number": 216,
"createdBy": "project:chromium-m134",
"createTime": "2025-03-07T19:36:21.504956731Z",
"updateTime": "2025-03-07T19:36:21.504956731Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fd54da84f56c041529a7223222c509ee39ccca04",
"ref": "refs/branch-heads/6998"
}
}
}
[19:36:21.944] Task URL: https://cr-buildbucket.appspot.com/build/8721028445061787857
[19:36:21.944] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:2:0) after 1m23s
[19:36:22.963] Received PubSub notification, asking Buildbucket for the build status
[19:36:23.017] Build status: SCHEDULED
[19:36:27.016] Received PubSub notification, asking Buildbucket for the build status
[19:36:27.041] Build status: STARTED
[19:37:44.951] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:2:0)
[19:37:44.980] Build status: STARTED
[19:37:44.980] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:5:0) after 2m34s
[19:40:19.047] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:5:0)
[19:40:19.079] Build status: STARTED
[19:40:19.079] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:6:0) after 9m27s
[19:49:46.147] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:6:0)
[19:49:46.170] Build status: STARTED
[19:49:46.170] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:7:0) after 5m10s
[19:54:56.216] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:7:0)
[19:54:56.238] Build status: STARTED
[19:54:56.238] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:8:0) after 7m56s
[20:02:52.311] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:8:0)
[20:02:52.355] Build status: STARTED
[20:02:52.355] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:9:0) after 6m38s
[20:09:30.380] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:9:0)
[20:09:30.415] Build status: STARTED
[20:09:30.415] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:10:0) after 7m54s
[20:17:24.578] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:10:0)
[20:17:24.603] Build status: STARTED
[20:17:24.603] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:11:0) after 5m59s
[20:23:23.630] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:11:0)
[20:23:23.654] Build status: STARTED
[20:23:23.654] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:12:0) after 4m51s
[20:28:14.671] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:12:0)
[20:28:14.688] Build status: STARTED
[20:28:14.688] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:13:0) after 6m51s
[20:35:05.746] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:13:0)
[20:35:05.772] Build status: STARTED
[20:35:05.772] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:14:0) after 4m52s
[20:39:57.801] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:14:0)
[20:39:57.819] Build status: STARTED
[20:39:57.819] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:15:0) after 9m56s
[20:49:53.844] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:15:0)
[20:49:53.861] Build status: STARTED
[20:49:53.861] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:16:0) after 4m0s
[20:53:53.885] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:16:0)
[20:53:53.907] Build status: STARTED
[20:53:53.907] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:17:0) after 4m52s
[20:58:45.936] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:17:0)
[20:58:45.952] Build status: STARTED
[20:58:45.952] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:18:0) after 4m37s
[21:03:22.998] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:18:0)
[21:03:23.063] Build status: STARTED
[21:03:23.063] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:19:0) after 5m54s
[21:09:17.081] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:19:0)
[21:09:17.101] Build status: STARTED
[21:09:17.101] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:20:0) after 5m23s
[21:14:40.154] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:20:0)
[21:14:40.171] Build status: STARTED
[21:14:40.171] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:21:0) after 7m10s
[21:21:50.197] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:21:0)
[21:21:50.220] Build status: STARTED
[21:21:50.220] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:22:0) after 2m6s
[21:23:56.246] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:22:0)
[21:23:56.267] Build status: STARTED
[21:23:56.267] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:23:0) after 7m34s
[21:31:30.318] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:23:0)
[21:31:30.404] Build status: STARTED
[21:31:30.404] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:24:0) after 8m24s
[21:39:54.427] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:24:0)
[21:39:54.455] Build status: STARTED
[21:39:54.455] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:25:0) after 1m36s
[21:41:30.482] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:25:0)
[21:41:30.502] Build status: STARTED
[21:41:30.502] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:26:0) after 6m39s
[21:48:09.609] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:26:0)
[21:48:09.625] Build status: STARTED
[21:48:09.625] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:27:0) after 6m55s
[21:55:04.661] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:27:0)
[21:55:04.678] Build status: STARTED
[21:55:04.678] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:28:0) after 5m6s
[22:00:10.697] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:28:0)
[22:00:10.729] Build status: STARTED
[22:00:10.729] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:29:0) after 8m56s
[22:09:06.896] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:29:0)
[22:09:06.931] Build status: STARTED
[22:09:06.931] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:30:0) after 9m57s
[22:19:04.022] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:30:0)
[22:19:04.149] Build status: STARTED
[22:19:04.149] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:31:0) after 8m6s
[22:27:10.171] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:31:0)
[22:27:10.188] Build status: STARTED
[22:27:10.188] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:32:0) after 8m57s
[22:36:07.310] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:32:0)
[22:36:07.339] Build status: STARTED
[22:36:07.339] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:33:0) after 8m19s
[22:44:26.318] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:33:0)
[22:44:26.336] Build status: STARTED
[22:44:26.336] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:34:0) after 2m27s
[22:46:53.416] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:34:0)
[22:46:53.462] Build status: STARTED
[22:46:53.462] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:35:0) after 5m38s
[22:52:31.605] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:35:0)
[22:52:31.658] Build status: STARTED
[22:52:31.658] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:36:0) after 5m32s
[22:58:03.770] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:36:0)
[22:58:03.791] Build status: STARTED
[22:58:03.791] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8886458507154785136:37:0) after 6m19s
[23:00:43.530] Received PubSub notification, asking Buildbucket for the build status
[23:00:43.551] Build:
{
"id": "8721028445061787857",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "win32-official"
},
"number": 216,
"createdBy": "project:chromium-m134",
"createTime": "2025-03-07T19:36:21.504956731Z",
"startTime": "2025-03-07T19:36:26.616144337Z",
"endTime": "2025-03-07T23:00:43.156651511Z",
"updateTime": "2025-03-07T23:00:43.156651511Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fd54da84f56c041529a7223222c509ee39ccca04",
"ref": "refs/branch-heads/6998"
}
}
}
[23:00:43.551] Invocation finished in 3h24m23.426741097s with status SUCCEEDED