[13:22:44.109] New invocation is queued and will start shortly
[13:22:45.222] Starting the invocation (attempt 1)
[13:22:45.252] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/77f5a797e076f8fd0e0b3c8d8b1bd73c52494089"
[13:22:45.252] Popped gitiles commit info from properties and tags
[13:22:45.252] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:22:45.252] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:22:45.252] Buildbucket request:
{
"requestId": "8879506045645506240",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8879506045645506240",
"job": "chromium-m137/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6a0d425c0a4dddcf13e426e606e0db4ff3840f9d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@6a0d425c0a4dddcf13e426e606e0db4ff3840f9d",
"title": "6a0d425c0a4dddcf13e426e606e0db4ff3840f9d",
"url": "https://chromium.googlesource.com/chromium/src/+/6a0d425c0a4dddcf13e426e606e0db4ff3840f9d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0fcf35f3362d1d66f90de4da2b1f5626b17507e8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@0fcf35f3362d1d66f90de4da2b1f5626b17507e8",
"title": "0fcf35f3362d1d66f90de4da2b1f5626b17507e8",
"url": "https://chromium.googlesource.com/chromium/src/+/0fcf35f3362d1d66f90de4da2b1f5626b17507e8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "530234e06164d61317239952f3745505a9bce3d4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@530234e06164d61317239952f3745505a9bce3d4",
"title": "530234e06164d61317239952f3745505a9bce3d4",
"url": "https://chromium.googlesource.com/chromium/src/+/530234e06164d61317239952f3745505a9bce3d4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9ed2de24aa4f85475c4cad7ac0276bbe7359b814"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@9ed2de24aa4f85475c4cad7ac0276bbe7359b814",
"title": "9ed2de24aa4f85475c4cad7ac0276bbe7359b814",
"url": "https://chromium.googlesource.com/chromium/src/+/9ed2de24aa4f85475c4cad7ac0276bbe7359b814"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "71ed32a0c2662232ba1733b2132ce9fef67b297c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@71ed32a0c2662232ba1733b2132ce9fef67b297c",
"title": "71ed32a0c2662232ba1733b2132ce9fef67b297c",
"url": "https://chromium.googlesource.com/chromium/src/+/71ed32a0c2662232ba1733b2132ce9fef67b297c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b4becd1945cfb02608f91574e86e3bc334235726"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@b4becd1945cfb02608f91574e86e3bc334235726",
"title": "b4becd1945cfb02608f91574e86e3bc334235726",
"url": "https://chromium.googlesource.com/chromium/src/+/b4becd1945cfb02608f91574e86e3bc334235726"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b0765f47f4cf965d502a420964d0e62508dbbdbd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@b0765f47f4cf965d502a420964d0e62508dbbdbd",
"title": "b0765f47f4cf965d502a420964d0e62508dbbdbd",
"url": "https://chromium.googlesource.com/chromium/src/+/b0765f47f4cf965d502a420964d0e62508dbbdbd"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "77f5a797e076f8fd0e0b3c8d8b1bd73c52494089"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@77f5a797e076f8fd0e0b3c8d8b1bd73c52494089",
"title": "77f5a797e076f8fd0e0b3c8d8b1bd73c52494089",
"url": "https://chromium.googlesource.com/chromium/src/+/77f5a797e076f8fd0e0b3c8d8b1bd73c52494089"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "77f5a797e076f8fd0e0b3c8d8b1bd73c52494089",
"ref": "refs/branch-heads/7151"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8879506045645506240"
},
{
"key": "scheduler_job_id",
"value": "chromium-m137/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[13:22:45.722] Scheduled build:
{
"id": "8714075983736545969",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "win32-official"
},
"number": 160,
"createdBy": "project:chromium-m137",
"createTime": "2025-05-23T13:22:45.305538726Z",
"updateTime": "2025-05-23T13:22:45.305538726Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "77f5a797e076f8fd0e0b3c8d8b1bd73c52494089",
"ref": "refs/branch-heads/7151"
}
}
}
[13:22:45.722] Task URL: https://cr-buildbucket.appspot.com/build/8714075983736545969
[13:22:45.722] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:2:0) after 1m26s
[13:22:46.745] Received PubSub notification, asking Buildbucket for the build status
[13:22:46.822] Build status: SCHEDULED
[13:22:49.559] Received PubSub notification, asking Buildbucket for the build status
[13:22:49.588] Build status: STARTED
[13:24:11.737] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:2:0)
[13:24:11.753] Build status: STARTED
[13:24:11.753] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:5:0) after 6m18s
[13:30:29.789] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:5:0)
[13:30:29.820] Build status: STARTED
[13:30:29.820] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:6:0) after 8m3s
[13:38:32.890] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:6:0)
[13:38:32.913] Build status: STARTED
[13:38:32.913] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:7:0) after 6m2s
[13:44:34.935] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:7:0)
[13:44:34.958] Build status: STARTED
[13:44:34.958] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:8:0) after 9m45s
[13:54:20.022] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:8:0)
[13:54:20.045] Build status: STARTED
[13:54:20.045] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:9:0) after 7m20s
[14:01:40.068] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:9:0)
[14:01:40.110] Build status: STARTED
[14:01:40.110] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:10:0) after 1m24s
[14:03:04.135] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:10:0)
[14:03:04.157] Build status: STARTED
[14:03:04.157] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:11:0) after 2m1s
[14:05:05.174] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:11:0)
[14:05:05.200] Build status: STARTED
[14:05:05.200] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:12:0) after 1m2s
[14:06:07.278] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:12:0)
[14:06:07.315] Build status: STARTED
[14:06:07.315] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:13:0) after 4m42s
[14:10:49.494] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:13:0)
[14:10:49.639] Build status: STARTED
[14:10:49.639] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:14:0) after 4m54s
[14:15:43.693] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:14:0)
[14:15:43.707] Build status: STARTED
[14:15:43.707] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:15:0) after 9m6s
[14:24:49.729] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:15:0)
[14:24:49.759] Build status: STARTED
[14:24:49.759] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:16:0) after 9m53s
[14:34:42.831] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:16:0)
[14:34:42.861] Build status: STARTED
[14:34:42.861] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:17:0) after 3m45s
[14:38:27.903] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:17:0)
[14:38:27.920] Build status: STARTED
[14:38:27.921] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:18:0) after 9m3s
[14:47:30.951] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:18:0)
[14:47:30.978] Build status: STARTED
[14:47:30.978] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:19:0) after 3m10s
[14:50:41.004] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:19:0)
[14:50:41.162] Build status: STARTED
[14:50:41.162] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:20:0) after 9m56s
[15:00:37.189] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:20:0)
[15:00:37.223] Build status: STARTED
[15:00:37.223] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:21:0) after 9m37s
[15:10:14.243] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:21:0)
[15:10:14.260] Build status: STARTED
[15:10:14.260] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:22:0) after 4m15s
[15:14:29.503] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:22:0)
[15:14:29.529] Build status: STARTED
[15:14:29.529] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:23:0) after 4m41s
[15:19:10.552] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:23:0)
[15:19:10.568] Build status: STARTED
[15:19:10.568] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:24:0) after 8m48s
[15:27:58.586] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:24:0)
[15:27:58.608] Build status: STARTED
[15:27:58.608] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:25:0) after 7m57s
[15:35:55.611] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:25:0)
[15:35:55.628] Build status: STARTED
[15:35:55.628] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:26:0) after 3m29s
[15:39:24.709] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:26:0)
[15:39:24.734] Build status: STARTED
[15:39:24.734] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:27:0) after 9m12s
[15:48:36.750] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:27:0)
[15:48:36.785] Build status: STARTED
[15:48:36.785] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:28:0) after 2m13s
[15:50:49.802] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:28:0)
[15:50:49.825] Build status: STARTED
[15:50:49.825] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:29:0) after 9m28s
[16:00:17.830] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:29:0)
[16:00:17.926] Build status: STARTED
[16:00:17.926] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:30:0) after 9m44s
[16:10:02.068] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:30:0)
[16:10:02.093] Build status: STARTED
[16:10:02.093] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:31:0) after 7m26s
[16:17:28.130] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:31:0)
[16:17:28.156] Build status: STARTED
[16:17:28.156] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:32:0) after 6m9s
[16:23:37.167] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:32:0)
[16:23:37.203] Build status: STARTED
[16:23:37.203] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:33:0) after 8m38s
[16:32:15.345] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:33:0)
[16:32:15.363] Build status: STARTED
[16:32:15.363] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:34:0) after 5m35s
[16:37:50.441] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:34:0)
[16:37:50.460] Build status: STARTED
[16:37:50.460] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:35:0) after 8m49s
[16:46:39.468] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:35:0)
[16:46:39.488] Build status: STARTED
[16:46:39.488] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:36:0) after 5m10s
[16:51:49.578] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:36:0)
[16:51:49.598] Build status: STARTED
[16:51:49.598] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:37:0) after 7m36s
[16:59:25.626] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:37:0)
[16:59:25.643] Build status: STARTED
[16:59:25.643] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:38:0) after 6m40s
[17:06:05.846] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:38:0)
[17:06:05.863] Build status: STARTED
[17:06:05.863] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8879506045645506240:39:0) after 8m55s
[17:10:56.056] Received PubSub notification, asking Buildbucket for the build status
[17:10:56.098] Build:
{
"id": "8714075983736545969",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "win32-official"
},
"number": 160,
"createdBy": "project:chromium-m137",
"createTime": "2025-05-23T13:22:45.305538726Z",
"startTime": "2025-05-23T13:22:49.383681748Z",
"endTime": "2025-05-23T17:10:55.732995638Z",
"updateTime": "2025-05-23T17:10:55.732995638Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "77f5a797e076f8fd0e0b3c8d8b1bd73c52494089",
"ref": "refs/branch-heads/7151"
}
}
}
[17:10:56.098] Invocation finished in 3h48m11.997733388s with status SUCCEEDED