[21:17:10.303] New invocation is queued and will start shortly
[21:17:12.444] Starting the invocation (attempt 1)
[21:17:12.466] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/08492d7451ac563ef149677569d26f2e4631fa66"
[21:17:12.466] Popped gitiles commit info from properties and tags
[21:17:12.466] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:17:12.467] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:17:12.467] Buildbucket request:
{
"requestId": "8884640223761041232",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8884640223761041232",
"job": "chromium-m135/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c0bdb8cba5eb2b5cd5b1873f085b785713d332d5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@c0bdb8cba5eb2b5cd5b1873f085b785713d332d5",
"title": "c0bdb8cba5eb2b5cd5b1873f085b785713d332d5",
"url": "https://chromium.googlesource.com/chromium/src/+/c0bdb8cba5eb2b5cd5b1873f085b785713d332d5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "15342ffb7d2ad83ffc307a250254f5236a7d368b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@15342ffb7d2ad83ffc307a250254f5236a7d368b",
"title": "15342ffb7d2ad83ffc307a250254f5236a7d368b",
"url": "https://chromium.googlesource.com/chromium/src/+/15342ffb7d2ad83ffc307a250254f5236a7d368b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b4f82026f0c50a9562d80c4add8231d17395b04c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@b4f82026f0c50a9562d80c4add8231d17395b04c",
"title": "b4f82026f0c50a9562d80c4add8231d17395b04c",
"url": "https://chromium.googlesource.com/chromium/src/+/b4f82026f0c50a9562d80c4add8231d17395b04c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "641e18b629d353136ad72c066332ddc687277845"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@641e18b629d353136ad72c066332ddc687277845",
"title": "641e18b629d353136ad72c066332ddc687277845",
"url": "https://chromium.googlesource.com/chromium/src/+/641e18b629d353136ad72c066332ddc687277845"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9e1824895d32ce533c2af84ac29cda24e7c301a2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@9e1824895d32ce533c2af84ac29cda24e7c301a2",
"title": "9e1824895d32ce533c2af84ac29cda24e7c301a2",
"url": "https://chromium.googlesource.com/chromium/src/+/9e1824895d32ce533c2af84ac29cda24e7c301a2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4bc8f2f912903486b7d4498d643d889799e33de7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@4bc8f2f912903486b7d4498d643d889799e33de7",
"title": "4bc8f2f912903486b7d4498d643d889799e33de7",
"url": "https://chromium.googlesource.com/chromium/src/+/4bc8f2f912903486b7d4498d643d889799e33de7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "404546f7d3a0a546dd2f071654eb5646e0692962"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@404546f7d3a0a546dd2f071654eb5646e0692962",
"title": "404546f7d3a0a546dd2f071654eb5646e0692962",
"url": "https://chromium.googlesource.com/chromium/src/+/404546f7d3a0a546dd2f071654eb5646e0692962"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6a03c0a1ed0c52222bb2997cd91de8d7032d793a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@6a03c0a1ed0c52222bb2997cd91de8d7032d793a",
"title": "6a03c0a1ed0c52222bb2997cd91de8d7032d793a",
"url": "https://chromium.googlesource.com/chromium/src/+/6a03c0a1ed0c52222bb2997cd91de8d7032d793a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4ccd708d50b7fb277be878346b12f22ab64dcb02"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@4ccd708d50b7fb277be878346b12f22ab64dcb02",
"title": "4ccd708d50b7fb277be878346b12f22ab64dcb02",
"url": "https://chromium.googlesource.com/chromium/src/+/4ccd708d50b7fb277be878346b12f22ab64dcb02"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "08492d7451ac563ef149677569d26f2e4631fa66"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@08492d7451ac563ef149677569d26f2e4631fa66",
"title": "08492d7451ac563ef149677569d26f2e4631fa66",
"url": "https://chromium.googlesource.com/chromium/src/+/08492d7451ac563ef149677569d26f2e4631fa66"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "08492d7451ac563ef149677569d26f2e4631fa66",
"ref": "refs/branch-heads/7049"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8884640223761041232"
},
{
"key": "scheduler_job_id",
"value": "chromium-m135/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:17:13.087] Scheduled build:
{
"id": "8719210160745707329",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "win32-official"
},
"number": 161,
"createdBy": "project:chromium-m135",
"createTime": "2025-03-27T21:17:12.556523069Z",
"updateTime": "2025-03-27T21:17:12.556523069Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "08492d7451ac563ef149677569d26f2e4631fa66",
"ref": "refs/branch-heads/7049"
}
}
}
[21:17:13.087] Task URL: https://cr-buildbucket.appspot.com/build/8719210160745707329
[21:17:13.087] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:2:0) after 6m38s
[21:17:14.082] Received PubSub notification, asking Buildbucket for the build status
[21:17:14.107] Build status: SCHEDULED
[21:18:42.688] Received PubSub notification, asking Buildbucket for the build status
[21:18:42.754] Build status: STARTED
[21:23:51.098] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:2:0)
[21:23:51.126] Build status: STARTED
[21:23:51.126] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:5:0) after 2m4s
[21:25:55.209] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:5:0)
[21:25:55.261] Build status: STARTED
[21:25:55.261] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:6:0) after 1m59s
[21:27:54.407] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:6:0)
[21:27:54.438] Build status: STARTED
[21:27:54.438] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:7:0) after 9m19s
[21:37:13.458] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:7:0)
[21:37:13.489] Build status: STARTED
[21:37:13.489] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:8:0) after 5m43s
[21:42:56.522] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:8:0)
[21:42:56.542] Build status: STARTED
[21:42:56.542] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:9:0) after 7m12s
[21:50:08.672] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:9:0)
[21:50:08.751] Build status: STARTED
[21:50:08.751] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:10:0) after 2m17s
[21:52:25.779] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:10:0)
[21:52:25.799] Build status: STARTED
[21:52:25.799] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:11:0) after 4m8s
[21:56:33.777] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:11:0)
[21:56:33.821] Build status: STARTED
[21:56:33.821] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:12:0) after 9m16s
[22:05:49.814] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:12:0)
[22:05:49.832] Build status: STARTED
[22:05:49.832] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:13:0) after 7m27s
[22:13:16.879] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:13:0)
[22:13:16.920] Build status: STARTED
[22:13:16.920] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:14:0) after 7m39s
[22:20:55.979] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:14:0)
[22:20:56.048] Build status: STARTED
[22:20:56.048] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:15:0) after 8m43s
[22:29:39.134] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:15:0)
[22:29:39.171] Build status: STARTED
[22:29:39.171] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:16:0) after 6m34s
[22:36:13.217] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:16:0)
[22:36:13.234] Build status: STARTED
[22:36:13.234] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:17:0) after 6m23s
[22:42:36.299] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:17:0)
[22:42:36.323] Build status: STARTED
[22:42:36.323] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:18:0) after 6m37s
[22:49:13.415] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:18:0)
[22:49:13.430] Build status: STARTED
[22:49:13.430] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:19:0) after 8m46s
[22:57:59.449] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:19:0)
[22:57:59.535] Build status: STARTED
[22:57:59.535] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:20:0) after 1m50s
[22:59:49.593] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:20:0)
[22:59:49.611] Build status: STARTED
[22:59:49.611] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:21:0) after 5m1s
[23:04:50.634] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:21:0)
[23:04:50.673] Build status: STARTED
[23:04:50.673] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:22:0) after 6m39s
[23:11:29.732] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:22:0)
[23:11:29.818] Build status: STARTED
[23:11:29.818] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:23:0) after 9m56s
[23:21:25.840] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:23:0)
[23:21:25.861] Build status: STARTED
[23:21:25.861] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:24:0) after 2m31s
[23:23:56.883] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:24:0)
[23:23:56.899] Build status: STARTED
[23:23:56.899] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:25:0) after 1m25s
[23:25:21.970] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:25:0)
[23:25:21.988] Build status: STARTED
[23:25:21.988] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:26:0) after 2m14s
[23:27:36.035] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:26:0)
[23:27:36.127] Build status: STARTED
[23:27:36.127] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:27:0) after 5m32s
[23:33:08.151] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:27:0)
[23:33:08.166] Build status: STARTED
[23:33:08.166] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:28:0) after 8m30s
[23:41:38.237] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:28:0)
[23:41:38.257] Build status: STARTED
[23:41:38.257] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:29:0) after 6m44s
[23:48:22.316] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:29:0)
[23:48:22.333] Build status: STARTED
[23:48:22.333] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:30:0) after 7m29s
[23:55:51.388] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:30:0)
[23:55:51.404] Build status: STARTED
[23:55:51.404] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:31:0) after 9m7s
[00:04:58.514] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:31:0)
[00:04:58.542] Build status: STARTED
[00:04:58.542] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:32:0) after 4m56s
[00:09:54.601] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:32:0)
[00:09:54.620] Build status: STARTED
[00:09:54.620] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:33:0) after 9m3s
[00:18:57.639] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:33:0)
[00:18:57.664] Build status: STARTED
[00:18:57.664] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:34:0) after 9m46s
[00:28:43.686] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:34:0)
[00:28:43.732] Build status: STARTED
[00:28:43.732] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:35:0) after 6m36s
[00:35:19.786] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:35:0)
[00:35:19.802] Build status: STARTED
[00:35:19.802] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:36:0) after 5m28s
[00:40:47.830] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:36:0)
[00:40:47.847] Build status: STARTED
[00:40:47.847] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8884640223761041232:37:0) after 2m24s
[00:42:50.542] Received PubSub notification, asking Buildbucket for the build status
[00:42:50.565] Build:
{
"id": "8719210160745707329",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "win32-official"
},
"number": 161,
"createdBy": "project:chromium-m135",
"createTime": "2025-03-27T21:17:12.556523069Z",
"startTime": "2025-03-27T21:18:42.475967363Z",
"endTime": "2025-03-28T00:42:24.321380200Z",
"updateTime": "2025-03-28T00:42:24.321380200Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "08492d7451ac563ef149677569d26f2e4631fa66",
"ref": "refs/branch-heads/7049"
}
}
}
[00:42:50.565] Invocation finished in 3h25m40.268279352s with status SUCCEEDED