[17:24:37.159] New invocation is queued and will start shortly
[17:24:38.348] Starting the invocation (attempt 1)
[17:24:38.380] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0fa0bd53a95924c920206a3362b9a102047fc703"
[17:24:38.380] Popped gitiles commit info from properties and tags
[17:24:38.380] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:24:38.380] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:24:38.381] Buildbucket request:
{
"requestId": "8883748885035447664",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8883748885035447664",
"job": "chromium-m136/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1500e10baed26b1a176ab9078f07cfee13deaac7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@1500e10baed26b1a176ab9078f07cfee13deaac7",
"title": "1500e10baed26b1a176ab9078f07cfee13deaac7",
"url": "https://chromium.googlesource.com/chromium/src/+/1500e10baed26b1a176ab9078f07cfee13deaac7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7a5addc1e1b13fd2838e8e4b6657b4f07a64140e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@7a5addc1e1b13fd2838e8e4b6657b4f07a64140e",
"title": "7a5addc1e1b13fd2838e8e4b6657b4f07a64140e",
"url": "https://chromium.googlesource.com/chromium/src/+/7a5addc1e1b13fd2838e8e4b6657b4f07a64140e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e738d328860a9d0a39c9a487d945d4184a6df194"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@e738d328860a9d0a39c9a487d945d4184a6df194",
"title": "e738d328860a9d0a39c9a487d945d4184a6df194",
"url": "https://chromium.googlesource.com/chromium/src/+/e738d328860a9d0a39c9a487d945d4184a6df194"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "eccd2e9476e0622a45b1d199d3acd96c632f2870"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@eccd2e9476e0622a45b1d199d3acd96c632f2870",
"title": "eccd2e9476e0622a45b1d199d3acd96c632f2870",
"url": "https://chromium.googlesource.com/chromium/src/+/eccd2e9476e0622a45b1d199d3acd96c632f2870"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9150476170229daeac05d7e9ad2ca72fe3b6d004"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@9150476170229daeac05d7e9ad2ca72fe3b6d004",
"title": "9150476170229daeac05d7e9ad2ca72fe3b6d004",
"url": "https://chromium.googlesource.com/chromium/src/+/9150476170229daeac05d7e9ad2ca72fe3b6d004"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1dc4089cdd5d5af880c9eaadd03c68c2e2a1d85f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@1dc4089cdd5d5af880c9eaadd03c68c2e2a1d85f",
"title": "1dc4089cdd5d5af880c9eaadd03c68c2e2a1d85f",
"url": "https://chromium.googlesource.com/chromium/src/+/1dc4089cdd5d5af880c9eaadd03c68c2e2a1d85f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bc6709b21615a7cceb3578b0884b190973f829f0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@bc6709b21615a7cceb3578b0884b190973f829f0",
"title": "bc6709b21615a7cceb3578b0884b190973f829f0",
"url": "https://chromium.googlesource.com/chromium/src/+/bc6709b21615a7cceb3578b0884b190973f829f0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9ecb4e3258ac828ef130484a83b9a78ae6a5e4e7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@9ecb4e3258ac828ef130484a83b9a78ae6a5e4e7",
"title": "9ecb4e3258ac828ef130484a83b9a78ae6a5e4e7",
"url": "https://chromium.googlesource.com/chromium/src/+/9ecb4e3258ac828ef130484a83b9a78ae6a5e4e7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d362aa7feb63d8e71dd0da1498303207a445166c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@d362aa7feb63d8e71dd0da1498303207a445166c",
"title": "d362aa7feb63d8e71dd0da1498303207a445166c",
"url": "https://chromium.googlesource.com/chromium/src/+/d362aa7feb63d8e71dd0da1498303207a445166c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0fa0bd53a95924c920206a3362b9a102047fc703"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@0fa0bd53a95924c920206a3362b9a102047fc703",
"title": "0fa0bd53a95924c920206a3362b9a102047fc703",
"url": "https://chromium.googlesource.com/chromium/src/+/0fa0bd53a95924c920206a3362b9a102047fc703"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0fa0bd53a95924c920206a3362b9a102047fc703",
"ref": "refs/branch-heads/7103"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8883748885035447664"
},
{
"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"
}
}
[17:24:39.061] Scheduled build:
{
"id": "8718318823040995889",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"number": 33,
"createdBy": "project:chromium-m136",
"createTime": "2025-04-06T17:24:38.432085558Z",
"updateTime": "2025-04-06T17:24:38.432085558Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0fa0bd53a95924c920206a3362b9a102047fc703",
"ref": "refs/branch-heads/7103"
}
}
}
[17:24:39.061] Task URL: https://cr-buildbucket.appspot.com/build/8718318823040995889
[17:24:39.061] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:2:0) after 7m19s
[17:24:39.243] Received PubSub notification, asking Buildbucket for the build status
[17:24:39.273] Build status: SCHEDULED
[17:26:05.920] Received PubSub notification, asking Buildbucket for the build status
[17:26:05.937] Build status: STARTED
[17:31:58.252] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:2:0)
[17:31:58.306] Build status: STARTED
[17:31:58.306] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:5:0) after 9m17s
[17:41:15.328] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:5:0)
[17:41:15.352] Build status: STARTED
[17:41:15.352] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:6:0) after 9m36s
[17:50:51.386] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:6:0)
[17:50:51.528] Build status: STARTED
[17:50:51.528] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:7:0) after 6m36s
[17:57:27.545] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:7:0)
[17:57:27.564] Build status: STARTED
[17:57:27.564] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:8:0) after 8m48s
[18:06:15.583] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:8:0)
[18:06:15.608] Build status: STARTED
[18:06:15.608] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:9:0) after 8m8s
[18:14:23.581] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:9:0)
[18:14:23.609] Build status: STARTED
[18:14:23.609] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:10:0) after 7m57s
[18:22:20.627] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:10:0)
[18:22:20.643] Build status: STARTED
[18:22:20.643] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:11:0) after 1m31s
[18:23:51.684] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:11:0)
[18:23:51.715] Build status: STARTED
[18:23:51.715] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:12:0) after 7m52s
[18:31:43.770] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:12:0)
[18:31:43.801] Build status: STARTED
[18:31:43.801] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:13:0) after 8m42s
[18:40:25.822] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:13:0)
[18:40:25.852] Build status: STARTED
[18:40:25.852] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:14:0) after 5m58s
[18:46:23.943] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:14:0)
[18:46:24.000] Build status: STARTED
[18:46:24.000] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:15:0) after 1m56s
[18:48:20.616] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:15:0)
[18:48:20.640] Build status: STARTED
[18:48:20.640] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:16:0) after 1m41s
[18:50:01.722] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:16:0)
[18:50:01.744] Build status: STARTED
[18:50:01.744] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:17:0) after 4m6s
[18:54:07.765] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:17:0)
[18:54:07.791] Build status: STARTED
[18:54:07.791] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:18:0) after 2m36s
[18:56:43.813] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:18:0)
[18:56:43.841] Build status: STARTED
[18:56:43.842] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:19:0) after 6m0s
[19:02:43.835] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:19:0)
[19:02:43.970] Build status: STARTED
[19:02:43.970] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:20:0) after 6m59s
[19:09:42.986] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:20:0)
[19:09:43.040] Build status: STARTED
[19:09:43.040] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:21:0) after 5m22s
[19:15:05.057] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:21:0)
[19:15:05.083] Build status: STARTED
[19:15:05.083] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:22:0) after 2m39s
[19:17:44.186] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:22:0)
[19:17:44.220] Build status: STARTED
[19:17:44.220] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:23:0) after 2m31s
[19:20:15.234] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:23:0)
[19:20:15.258] Build status: STARTED
[19:20:15.258] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:24:0) after 3m27s
[19:23:42.431] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:24:0)
[19:23:42.460] Build status: STARTED
[19:23:42.460] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:25:0) after 2m7s
[19:25:49.480] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:25:0)
[19:25:49.565] Build status: STARTED
[19:25:49.565] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:26:0) after 2m1s
[19:27:50.705] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:26:0)
[19:27:50.726] Build status: STARTED
[19:27:50.726] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:27:0) after 7m49s
[19:35:39.891] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:27:0)
[19:35:39.914] Build status: STARTED
[19:35:39.914] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:28:0) after 3m23s
[19:39:02.935] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:28:0)
[19:39:03.072] Build status: STARTED
[19:39:03.072] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:29:0) after 6m5s
[19:45:08.091] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:29:0)
[19:45:08.152] Build status: STARTED
[19:45:08.152] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:30:0) after 6m24s
[19:51:32.368] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:30:0)
[19:51:32.386] Build status: STARTED
[19:51:32.386] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:31:0) after 8m35s
[20:00:07.459] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:31:0)
[20:00:07.483] Build status: STARTED
[20:00:07.484] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:32:0) after 7m26s
[20:07:33.507] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:32:0)
[20:07:33.526] Build status: STARTED
[20:07:33.526] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:33:0) after 9m13s
[20:16:46.668] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:33:0)
[20:16:46.688] Build status: STARTED
[20:16:46.688] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:34:0) after 9m24s
[20:26:10.707] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:34:0)
[20:26:10.731] Build status: STARTED
[20:26:10.732] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:35:0) after 7m34s
[20:33:44.752] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:35:0)
[20:33:44.770] Build status: STARTED
[20:33:44.770] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:36:0) after 8m29s
[20:42:13.793] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:36:0)
[20:42:13.814] Build status: STARTED
[20:42:13.814] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:37:0) after 5m16s
[20:47:29.815] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:37:0)
[20:47:29.829] Build status: STARTED
[20:47:29.829] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:38:0) after 4m39s
[20:52:08.934] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:38:0)
[20:52:09.134] Build status: STARTED
[20:52:09.134] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:39:0) after 3m31s
[20:55:40.217] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:39:0)
[20:55:40.235] Build status: STARTED
[20:55:40.235] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:40:0) after 1m28s
[20:57:08.353] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:40:0)
[20:57:08.404] Build status: STARTED
[20:57:08.404] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:41:0) after 7m55s
[21:05:03.424] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:41:0)
[21:05:03.440] Build status: STARTED
[21:05:03.440] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:42:0) after 4m40s
[21:09:43.496] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:42:0)
[21:09:43.525] Build status: STARTED
[21:09:43.525] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:43:0) after 4m52s
[21:14:35.545] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:43:0)
[21:14:35.647] Build status: STARTED
[21:14:35.647] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:44:0) after 2m57s
[21:17:32.717] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:44:0)
[21:17:32.904] Build status: STARTED
[21:17:32.904] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:45:0) after 1m36s
[21:19:08.940] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:45:0)
[21:19:09.021] Build status: STARTED
[21:19:09.021] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8883748885035447664:46:0) after 7m47s
[21:20:25.517] Received PubSub notification, asking Buildbucket for the build status
[21:20:25.530] Build:
{
"id": "8718318823040995889",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"number": 33,
"createdBy": "project:chromium-m136",
"createTime": "2025-04-06T17:24:38.432085558Z",
"startTime": "2025-04-06T17:26:05.757600011Z",
"endTime": "2025-04-06T21:20:25.140256585Z",
"updateTime": "2025-04-06T21:20:25.140256585Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0fa0bd53a95924c920206a3362b9a102047fc703",
"ref": "refs/branch-heads/7103"
}
}
}
[21:20:25.530] Invocation finished in 3h55m48.383329981s with status SUCCEEDED