[18:25:35.452] New invocation is queued and will start shortly
[18:25:36.728] Starting the invocation (attempt 1)
[18:25:36.753] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ce9f626408e2411ec486726b4dfdb1e8d596520a"
[18:25:36.753] Popped gitiles commit info from properties and tags
[18:25:36.753] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:25:36.754] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:25:36.754] Buildbucket request:
{
"requestId": "8881389527908704560",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8881389527908704560",
"job": "chromium-m136/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "369f6733ec1760158759f1b846f4f8af121cce56"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@369f6733ec1760158759f1b846f4f8af121cce56",
"title": "369f6733ec1760158759f1b846f4f8af121cce56",
"url": "https://chromium.googlesource.com/chromium/src/+/369f6733ec1760158759f1b846f4f8af121cce56"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "010f9215177fce42278893bf04ffd188fc1ca084"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@010f9215177fce42278893bf04ffd188fc1ca084",
"title": "010f9215177fce42278893bf04ffd188fc1ca084",
"url": "https://chromium.googlesource.com/chromium/src/+/010f9215177fce42278893bf04ffd188fc1ca084"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "14b32a3a8ba040f77e76f17ddcb19b32b14936fb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@14b32a3a8ba040f77e76f17ddcb19b32b14936fb",
"title": "14b32a3a8ba040f77e76f17ddcb19b32b14936fb",
"url": "https://chromium.googlesource.com/chromium/src/+/14b32a3a8ba040f77e76f17ddcb19b32b14936fb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "842073584e848ce7d83bc3bc99496f30bc6db4fd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@842073584e848ce7d83bc3bc99496f30bc6db4fd",
"title": "842073584e848ce7d83bc3bc99496f30bc6db4fd",
"url": "https://chromium.googlesource.com/chromium/src/+/842073584e848ce7d83bc3bc99496f30bc6db4fd"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4e0db7398bbc568958a49da91227cf2a8c571496"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@4e0db7398bbc568958a49da91227cf2a8c571496",
"title": "4e0db7398bbc568958a49da91227cf2a8c571496",
"url": "https://chromium.googlesource.com/chromium/src/+/4e0db7398bbc568958a49da91227cf2a8c571496"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a3dbd00793c96c8ca508ad0b444b772f9e2230c8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@a3dbd00793c96c8ca508ad0b444b772f9e2230c8",
"title": "a3dbd00793c96c8ca508ad0b444b772f9e2230c8",
"url": "https://chromium.googlesource.com/chromium/src/+/a3dbd00793c96c8ca508ad0b444b772f9e2230c8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "276aadb1a1b623007c5bad4fdc02f99b08d3e135"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@276aadb1a1b623007c5bad4fdc02f99b08d3e135",
"title": "276aadb1a1b623007c5bad4fdc02f99b08d3e135",
"url": "https://chromium.googlesource.com/chromium/src/+/276aadb1a1b623007c5bad4fdc02f99b08d3e135"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ce9f626408e2411ec486726b4dfdb1e8d596520a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@ce9f626408e2411ec486726b4dfdb1e8d596520a",
"title": "ce9f626408e2411ec486726b4dfdb1e8d596520a",
"url": "https://chromium.googlesource.com/chromium/src/+/ce9f626408e2411ec486726b4dfdb1e8d596520a"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ce9f626408e2411ec486726b4dfdb1e8d596520a",
"ref": "refs/branch-heads/7103"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8881389527908704560"
},
{
"key": "scheduler_job_id",
"value": "chromium-m136/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:25:37.242] Scheduled build:
{
"id": "8715959465835974945",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"number": 204,
"createdBy": "project:chromium-m136",
"createTime": "2025-05-02T18:25:36.802798330Z",
"updateTime": "2025-05-02T18:25:36.802798330Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ce9f626408e2411ec486726b4dfdb1e8d596520a",
"ref": "refs/branch-heads/7103"
}
}
}
[18:25:37.242] Task URL: https://cr-buildbucket.appspot.com/build/8715959465835974945
[18:25:37.242] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:2:0) after 5m13s
[18:25:38.204] Received PubSub notification, asking Buildbucket for the build status
[18:25:38.221] Build status: SCHEDULED
[18:26:58.097] Received PubSub notification, asking Buildbucket for the build status
[18:26:58.113] Build status: STARTED
[18:30:50.336] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:2:0)
[18:30:50.393] Build status: STARTED
[18:30:50.393] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:5:0) after 3m36s
[18:34:26.384] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:5:0)
[18:34:26.418] Build status: STARTED
[18:34:26.418] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:6:0) after 3m45s
[18:38:11.393] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:6:0)
[18:38:11.416] Build status: STARTED
[18:38:11.416] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:7:0) after 5m54s
[18:44:05.505] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:7:0)
[18:44:05.528] Build status: STARTED
[18:44:05.528] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:8:0) after 6m44s
[18:50:49.551] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:8:0)
[18:50:49.576] Build status: STARTED
[18:50:49.576] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:9:0) after 3m15s
[18:54:04.632] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:9:0)
[18:54:04.651] Build status: STARTED
[18:54:04.651] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:10:0) after 4m48s
[18:58:52.772] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:10:0)
[18:58:52.806] Build status: STARTED
[18:58:52.806] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:11:0) after 6m24s
[19:05:16.899] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:11:0)
[19:05:16.914] Build status: STARTED
[19:05:16.914] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:12:0) after 3m37s
[19:08:53.975] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:12:0)
[19:08:54.005] Build status: STARTED
[19:08:54.005] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:13:0) after 1m32s
[19:10:26.031] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:13:0)
[19:10:26.101] Build status: STARTED
[19:10:26.101] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:14:0) after 9m55s
[19:20:21.172] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:14:0)
[19:20:21.194] Build status: STARTED
[19:20:21.194] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:15:0) after 6m53s
[19:27:14.223] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:15:0)
[19:27:14.486] Build status: STARTED
[19:27:14.486] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:16:0) after 4m58s
[19:32:12.612] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:16:0)
[19:32:12.647] Build status: STARTED
[19:32:12.647] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:17:0) after 3m47s
[19:35:59.646] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:17:0)
[19:35:59.670] Build status: STARTED
[19:35:59.670] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:18:0) after 3m49s
[19:39:48.686] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:18:0)
[19:39:48.742] Build status: STARTED
[19:39:48.742] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:19:0) after 6m29s
[19:46:17.739] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:19:0)
[19:46:17.755] Build status: STARTED
[19:46:17.755] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:20:0) after 5m59s
[19:52:16.781] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:20:0)
[19:52:16.803] Build status: STARTED
[19:52:16.803] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:21:0) after 5m55s
[19:58:11.824] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:21:0)
[19:58:11.840] Build status: STARTED
[19:58:11.840] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:22:0) after 7m21s
[20:05:32.887] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:22:0)
[20:05:32.974] Build status: STARTED
[20:05:32.974] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:23:0) after 4m2s
[20:09:35.010] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:23:0)
[20:09:35.030] Build status: STARTED
[20:09:35.030] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:24:0) after 4m56s
[20:14:31.111] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:24:0)
[20:14:31.130] Build status: STARTED
[20:14:31.130] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:25:0) after 8m58s
[20:23:29.254] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:25:0)
[20:23:29.273] Build status: STARTED
[20:23:29.273] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:26:0) after 1m33s
[20:25:02.300] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:26:0)
[20:25:02.323] Build status: STARTED
[20:25:02.323] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:27:0) after 6m10s
[20:31:12.351] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:27:0)
[20:31:12.372] Build status: STARTED
[20:31:12.372] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:28:0) after 9m8s
[20:40:20.493] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:28:0)
[20:40:20.527] Build status: STARTED
[20:40:20.528] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:29:0) after 1m10s
[20:41:30.605] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:29:0)
[20:41:30.626] Build status: STARTED
[20:41:30.626] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:30:0) after 1m31s
[20:43:01.694] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:30:0)
[20:43:01.714] Build status: STARTED
[20:43:01.714] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:31:0) after 5m7s
[20:48:08.788] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:31:0)
[20:48:08.802] Build status: STARTED
[20:48:08.803] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:32:0) after 9m57s
[20:58:05.867] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:32:0)
[20:58:05.886] Build status: STARTED
[20:58:05.886] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:33:0) after 7m14s
[21:05:19.959] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:33:0)
[21:05:20.022] Build status: STARTED
[21:05:20.022] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:34:0) after 9m37s
[21:14:57.040] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:34:0)
[21:14:57.187] Build status: STARTED
[21:14:57.187] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:35:0) after 6m32s
[21:21:29.193] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:35:0)
[21:21:29.209] Build status: STARTED
[21:21:29.209] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:36:0) after 1m5s
[21:22:34.237] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:36:0)
[21:22:34.256] Build status: STARTED
[21:22:34.256] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:37:0) after 2m47s
[21:25:21.287] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:37:0)
[21:25:21.303] Build status: STARTED
[21:25:21.303] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:38:0) after 2m29s
[21:27:50.443] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:38:0)
[21:27:50.458] Build status: STARTED
[21:27:50.458] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:39:0) after 1m7s
[21:28:57.480] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:39:0)
[21:28:57.501] Build status: STARTED
[21:28:57.502] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:40:0) after 5m11s
[21:34:08.529] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:40:0)
[21:34:08.552] Build status: STARTED
[21:34:08.552] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:41:0) after 9m42s
[21:43:50.625] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:41:0)
[21:43:50.643] Build status: STARTED
[21:43:50.643] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:42:0) after 3m5s
[21:46:55.670] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:42:0)
[21:46:55.688] Build status: STARTED
[21:46:55.688] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:43:0) after 1m22s
[21:48:17.714] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:43:0)
[21:48:17.733] Build status: STARTED
[21:48:17.733] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:44:0) after 2m43s
[21:51:00.752] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:44:0)
[21:51:00.833] Build status: STARTED
[21:51:00.833] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:45:0) after 4m54s
[21:55:54.862] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:45:0)
[21:55:54.909] Build status: STARTED
[21:55:54.909] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:46:0) after 9m49s
[22:05:44.022] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:46:0)
[22:05:44.038] Build status: STARTED
[22:05:44.038] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:47:0) after 2m12s
[22:07:56.082] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:47:0)
[22:07:56.098] Build status: STARTED
[22:07:56.098] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8881389527908704560:48:0) after 8m56s
[22:08:15.474] Received PubSub notification, asking Buildbucket for the build status
[22:08:15.498] Build:
{
"id": "8715959465835974945",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"number": 204,
"createdBy": "project:chromium-m136",
"createTime": "2025-05-02T18:25:36.802798330Z",
"startTime": "2025-05-02T18:26:57.783550223Z",
"endTime": "2025-05-02T22:08:15.047255611Z",
"updateTime": "2025-05-02T22:08:15.047255611Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ce9f626408e2411ec486726b4dfdb1e8d596520a",
"ref": "refs/branch-heads/7103"
}
}
}
[22:08:15.498] Invocation finished in 3h42m40.056130247s with status SUCCEEDED