[16:45:30.710] New invocation is queued and will start shortly
[16:45:31.817] Starting the invocation (attempt 1)
[16:45:31.841] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8b126491b3812d360421a55cf33ad91aae9ce762"
[16:45:31.841] Popped gitiles commit info from properties and tags
[16:45:31.841] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:45:31.842] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:45:31.842] Buildbucket request:
{
"requestId": "8884747912095144272",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8884747912095144272",
"job": "chromium-m134/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bad07bd589ea8de9df089a52be5ee7944083f5be"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@bad07bd589ea8de9df089a52be5ee7944083f5be",
"title": "bad07bd589ea8de9df089a52be5ee7944083f5be",
"url": "https://chromium.googlesource.com/chromium/src/+/bad07bd589ea8de9df089a52be5ee7944083f5be"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "744f3193d3c1f5646e3ef0c7a38709364fc3e73b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@744f3193d3c1f5646e3ef0c7a38709364fc3e73b",
"title": "744f3193d3c1f5646e3ef0c7a38709364fc3e73b",
"url": "https://chromium.googlesource.com/chromium/src/+/744f3193d3c1f5646e3ef0c7a38709364fc3e73b"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "84da683577ec8397ea10cc09a1bf780441583aaa"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@84da683577ec8397ea10cc09a1bf780441583aaa",
"title": "84da683577ec8397ea10cc09a1bf780441583aaa",
"url": "https://chromium.googlesource.com/chromium/src/+/84da683577ec8397ea10cc09a1bf780441583aaa"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6ada02b437b78178cba3af9a2e6710f477537020"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@6ada02b437b78178cba3af9a2e6710f477537020",
"title": "6ada02b437b78178cba3af9a2e6710f477537020",
"url": "https://chromium.googlesource.com/chromium/src/+/6ada02b437b78178cba3af9a2e6710f477537020"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e1843491d8b50c2ed7dfee7cae11ac10674e03e1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@e1843491d8b50c2ed7dfee7cae11ac10674e03e1",
"title": "e1843491d8b50c2ed7dfee7cae11ac10674e03e1",
"url": "https://chromium.googlesource.com/chromium/src/+/e1843491d8b50c2ed7dfee7cae11ac10674e03e1"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7e38c1de5416466a08228c979af183fc6afbb194"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@7e38c1de5416466a08228c979af183fc6afbb194",
"title": "7e38c1de5416466a08228c979af183fc6afbb194",
"url": "https://chromium.googlesource.com/chromium/src/+/7e38c1de5416466a08228c979af183fc6afbb194"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "255c978b24894a309a93219161afd3a04e3f0878"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@255c978b24894a309a93219161afd3a04e3f0878",
"title": "255c978b24894a309a93219161afd3a04e3f0878",
"url": "https://chromium.googlesource.com/chromium/src/+/255c978b24894a309a93219161afd3a04e3f0878"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "42748916b38b53e889fcd9a68c7a1e23e528c8f6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@42748916b38b53e889fcd9a68c7a1e23e528c8f6",
"title": "42748916b38b53e889fcd9a68c7a1e23e528c8f6",
"url": "https://chromium.googlesource.com/chromium/src/+/42748916b38b53e889fcd9a68c7a1e23e528c8f6"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8b126491b3812d360421a55cf33ad91aae9ce762"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@8b126491b3812d360421a55cf33ad91aae9ce762",
"title": "8b126491b3812d360421a55cf33ad91aae9ce762",
"url": "https://chromium.googlesource.com/chromium/src/+/8b126491b3812d360421a55cf33ad91aae9ce762"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8b126491b3812d360421a55cf33ad91aae9ce762",
"ref": "refs/branch-heads/6998"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8884747912095144272"
},
{
"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"
}
}
[16:45:32.429] Scheduled build:
{
"id": "8719317850187726225",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "win32-official"
},
"number": 324,
"createdBy": "project:chromium-m134",
"createTime": "2025-03-26T16:45:31.901045672Z",
"updateTime": "2025-03-26T16:45:31.901045672Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8b126491b3812d360421a55cf33ad91aae9ce762",
"ref": "refs/branch-heads/6998"
}
}
}
[16:45:32.429] Task URL: https://cr-buildbucket.appspot.com/build/8719317850187726225
[16:45:32.429] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:2:0) after 8m36s
[16:45:33.466] Received PubSub notification, asking Buildbucket for the build status
[16:45:33.486] Build status: SCHEDULED
[16:46:57.974] Received PubSub notification, asking Buildbucket for the build status
[16:46:58.072] Build status: STARTED
[16:54:08.486] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:2:0)
[16:54:08.508] Build status: STARTED
[16:54:08.508] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:5:0) after 7m32s
[17:01:40.657] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:5:0)
[17:01:40.683] Build status: STARTED
[17:01:40.683] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:6:0) after 3m56s
[17:05:36.700] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:6:0)
[17:05:36.770] Build status: STARTED
[17:05:36.770] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:7:0) after 6m47s
[17:12:23.802] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:7:0)
[17:12:23.861] Build status: STARTED
[17:12:23.861] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:8:0) after 7m30s
[17:19:53.886] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:8:0)
[17:19:53.909] Build status: STARTED
[17:19:53.909] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:9:0) after 5m55s
[17:25:48.891] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:9:0)
[17:25:48.947] Build status: STARTED
[17:25:48.947] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:10:0) after 4m55s
[17:30:44.093] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:10:0)
[17:30:44.117] Build status: STARTED
[17:30:44.117] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:11:0) after 9m36s
[17:40:20.240] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:11:0)
[17:40:20.258] Build status: STARTED
[17:40:20.258] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:12:0) after 3m7s
[17:43:27.277] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:12:0)
[17:43:27.292] Build status: STARTED
[17:43:27.292] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:13:0) after 6m10s
[17:49:37.462] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:13:0)
[17:49:37.485] Build status: STARTED
[17:49:37.486] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:14:0) after 5m26s
[17:55:03.508] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:14:0)
[17:55:03.526] Build status: STARTED
[17:55:03.526] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:15:0) after 5m50s
[18:00:53.592] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:15:0)
[18:00:53.654] Build status: STARTED
[18:00:53.654] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:16:0) after 3m11s
[18:04:04.693] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:16:0)
[18:04:04.722] Build status: STARTED
[18:04:04.722] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:17:0) after 7m9s
[18:11:13.868] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:17:0)
[18:11:13.908] Build status: STARTED
[18:11:13.909] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:18:0) after 1m3s
[18:12:17.009] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:18:0)
[18:12:17.031] Build status: STARTED
[18:12:17.031] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:19:0) after 1m4s
[18:13:21.080] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:19:0)
[18:13:21.100] Build status: STARTED
[18:13:21.100] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:20:0) after 5m20s
[18:18:41.167] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:20:0)
[18:18:41.186] Build status: STARTED
[18:18:41.186] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:21:0) after 5m35s
[18:24:16.216] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:21:0)
[18:24:16.241] Build status: STARTED
[18:24:16.241] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:22:0) after 5m55s
[18:30:11.340] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:22:0)
[18:30:11.366] Build status: STARTED
[18:30:11.366] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:23:0) after 3m45s
[18:33:56.415] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:23:0)
[18:33:56.445] Build status: STARTED
[18:33:56.445] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:24:0) after 5m45s
[18:39:41.444] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:24:0)
[18:39:41.461] Build status: STARTED
[18:39:41.461] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:25:0) after 2m21s
[18:42:02.639] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:25:0)
[18:42:02.665] Build status: STARTED
[18:42:02.665] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:26:0) after 4m35s
[18:46:37.757] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:26:0)
[18:46:37.789] Build status: STARTED
[18:46:37.789] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:27:0) after 8m6s
[18:54:43.923] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:27:0)
[18:54:43.943] Build status: STARTED
[18:54:43.943] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:28:0) after 2m43s
[18:57:27.067] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:28:0)
[18:57:27.084] Build status: STARTED
[18:57:27.084] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:29:0) after 8m30s
[19:05:57.113] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:29:0)
[19:05:57.131] Build status: STARTED
[19:05:57.131] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:30:0) after 8m17s
[19:14:14.161] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:30:0)
[19:14:14.184] Build status: STARTED
[19:14:14.184] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:31:0) after 5m22s
[19:19:36.238] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:31:0)
[19:19:36.261] Build status: STARTED
[19:19:36.261] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:32:0) after 4m56s
[19:24:32.373] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:32:0)
[19:24:32.392] Build status: STARTED
[19:24:32.392] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:33:0) after 4m18s
[19:28:50.375] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:33:0)
[19:28:50.393] Build status: STARTED
[19:28:50.393] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:34:0) after 1m57s
[19:30:47.414] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:34:0)
[19:30:47.432] Build status: STARTED
[19:30:47.432] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:35:0) after 6m7s
[19:36:54.505] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:35:0)
[19:36:54.525] Build status: STARTED
[19:36:54.525] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:36:0) after 9m59s
[19:46:53.691] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:36:0)
[19:46:53.709] Build status: STARTED
[19:46:53.709] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:37:0) after 6m34s
[19:53:27.737] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:37:0)
[19:53:27.757] Build status: STARTED
[19:53:27.757] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:38:0) after 2m18s
[19:55:45.782] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:38:0)
[19:55:45.809] Build status: STARTED
[19:55:45.809] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:39:0) after 4m32s
[20:00:17.829] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:39:0)
[20:00:17.847] Build status: STARTED
[20:00:17.847] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:40:0) after 5m2s
[20:05:19.919] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:40:0)
[20:05:19.944] Build status: STARTED
[20:05:19.944] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884747912095144272:41:0) after 4m58s
[20:09:12.178] Received PubSub notification, asking Buildbucket for the build status
[20:09:12.194] Build:
{
"id": "8719317850187726225",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "win32-official"
},
"number": 324,
"createdBy": "project:chromium-m134",
"createTime": "2025-03-26T16:45:31.901045672Z",
"startTime": "2025-03-26T16:46:57.649982093Z",
"endTime": "2025-03-26T20:09:11.232808795Z",
"updateTime": "2025-03-26T20:09:11.232808795Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8b126491b3812d360421a55cf33ad91aae9ce762",
"ref": "refs/branch-heads/6998"
}
}
}
[20:09:12.194] Invocation finished in 3h23m41.496438017s with status SUCCEEDED