[10:03:40.432] New invocation is queued and will start shortly
[10:03:41.575] Starting the invocation (attempt 1)
[10:03:41.606] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/062ada5abb0f541b14e39cab611201ef84688eb0"
[10:03:41.607] Popped gitiles commit info from properties and tags
[10:03:41.607] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:03:41.607] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:03:41.607] Buildbucket request:
{
"requestId": "8876619466582460224",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8876619466582460224",
"job": "chromium-m137/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c5f5c4fd6bcced37ffb7c0f5ab65232629d72a84"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@c5f5c4fd6bcced37ffb7c0f5ab65232629d72a84",
"title": "c5f5c4fd6bcced37ffb7c0f5ab65232629d72a84",
"url": "https://chromium.googlesource.com/chromium/src/+/c5f5c4fd6bcced37ffb7c0f5ab65232629d72a84"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "02b9708a1c31f2f7c47f73efb5871d63ecda9a39"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@02b9708a1c31f2f7c47f73efb5871d63ecda9a39",
"title": "02b9708a1c31f2f7c47f73efb5871d63ecda9a39",
"url": "https://chromium.googlesource.com/chromium/src/+/02b9708a1c31f2f7c47f73efb5871d63ecda9a39"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "17a26fb78ff8957f61a53ebec92b07f028f2e7d8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@17a26fb78ff8957f61a53ebec92b07f028f2e7d8",
"title": "17a26fb78ff8957f61a53ebec92b07f028f2e7d8",
"url": "https://chromium.googlesource.com/chromium/src/+/17a26fb78ff8957f61a53ebec92b07f028f2e7d8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bd4171fd488f8601ef67b1dbf028dfec824d07c7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@bd4171fd488f8601ef67b1dbf028dfec824d07c7",
"title": "bd4171fd488f8601ef67b1dbf028dfec824d07c7",
"url": "https://chromium.googlesource.com/chromium/src/+/bd4171fd488f8601ef67b1dbf028dfec824d07c7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "88ac68ead3020b6e4da434102af1f1ca9b37dbfc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@88ac68ead3020b6e4da434102af1f1ca9b37dbfc",
"title": "88ac68ead3020b6e4da434102af1f1ca9b37dbfc",
"url": "https://chromium.googlesource.com/chromium/src/+/88ac68ead3020b6e4da434102af1f1ca9b37dbfc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "213cf35925fbd494b82b10ffd7db034710565d1b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@213cf35925fbd494b82b10ffd7db034710565d1b",
"title": "213cf35925fbd494b82b10ffd7db034710565d1b",
"url": "https://chromium.googlesource.com/chromium/src/+/213cf35925fbd494b82b10ffd7db034710565d1b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5695bd899ae254c555716f1de6649bdd4b8f2594"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@5695bd899ae254c555716f1de6649bdd4b8f2594",
"title": "5695bd899ae254c555716f1de6649bdd4b8f2594",
"url": "https://chromium.googlesource.com/chromium/src/+/5695bd899ae254c555716f1de6649bdd4b8f2594"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "062ada5abb0f541b14e39cab611201ef84688eb0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@062ada5abb0f541b14e39cab611201ef84688eb0",
"title": "062ada5abb0f541b14e39cab611201ef84688eb0",
"url": "https://chromium.googlesource.com/chromium/src/+/062ada5abb0f541b14e39cab611201ef84688eb0"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "062ada5abb0f541b14e39cab611201ef84688eb0",
"ref": "refs/branch-heads/7151"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8876619466582460224"
},
{
"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"
}
}
[10:03:42.324] Scheduled build:
{
"id": "8711189404589687585",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "win32-official"
},
"number": 346,
"createdBy": "project:chromium-m137",
"createTime": "2025-06-24T10:03:41.700163818Z",
"updateTime": "2025-06-24T10:03:41.700163818Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "062ada5abb0f541b14e39cab611201ef84688eb0",
"ref": "refs/branch-heads/7151"
}
},
"canary": true
}
[10:03:42.324] Task URL: https://cr-buildbucket.appspot.com/build/8711189404589687585
[10:03:42.324] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:2:0) after 9m32s
[10:03:43.326] Received PubSub notification, asking Buildbucket for the build status
[10:03:43.357] Build status: SCHEDULED
[10:03:46.735] Received PubSub notification, asking Buildbucket for the build status
[10:03:46.770] Build status: STARTED
[10:13:14.446] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:2:0)
[10:13:14.522] Build status: STARTED
[10:13:14.522] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:5:0) after 9m0s
[10:22:14.594] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:5:0)
[10:22:14.620] Build status: STARTED
[10:22:14.620] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:6:0) after 3m21s
[10:25:35.735] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:6:0)
[10:25:35.768] Build status: STARTED
[10:25:35.768] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:7:0) after 9m2s
[10:34:37.909] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:7:0)
[10:34:37.932] Build status: STARTED
[10:34:37.932] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:8:0) after 3m35s
[10:38:12.958] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:8:0)
[10:38:13.022] Build status: STARTED
[10:38:13.022] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:9:0) after 5m50s
[10:44:03.296] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:9:0)
[10:44:03.317] Build status: STARTED
[10:44:03.317] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:10:0) after 2m26s
[10:46:29.828] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:10:0)
[10:46:29.862] Build status: STARTED
[10:46:29.862] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:11:0) after 3m14s
[10:49:43.938] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:11:0)
[10:49:43.965] Build status: STARTED
[10:49:43.965] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:12:0) after 7m55s
[10:57:38.996] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:12:0)
[10:57:39.049] Build status: STARTED
[10:57:39.050] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:13:0) after 9m21s
[11:07:00.120] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:13:0)
[11:07:00.165] Build status: STARTED
[11:07:00.165] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:14:0) after 5m39s
[11:12:39.172] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:14:0)
[11:12:39.190] Build status: STARTED
[11:12:39.190] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:15:0) after 3m17s
[11:15:56.254] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:15:0)
[11:15:56.349] Build status: STARTED
[11:15:56.349] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:16:0) after 3m8s
[11:19:04.371] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:16:0)
[11:19:04.394] Build status: STARTED
[11:19:04.394] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:17:0) after 6m12s
[11:25:16.605] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:17:0)
[11:25:16.638] Build status: STARTED
[11:25:16.638] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:18:0) after 8m58s
[11:34:14.734] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:18:0)
[11:34:14.917] Build status: STARTED
[11:34:14.917] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:19:0) after 5m34s
[11:39:49.050] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:19:0)
[11:39:49.069] Build status: STARTED
[11:39:49.069] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:20:0) after 9m6s
[11:48:55.579] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:20:0)
[11:48:55.600] Build status: STARTED
[11:48:55.600] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:21:0) after 3m37s
[11:52:32.743] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:21:0)
[11:52:32.762] Build status: STARTED
[11:52:32.762] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:22:0) after 8m36s
[12:01:08.886] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:22:0)
[12:01:08.910] Build status: STARTED
[12:01:08.910] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:23:0) after 8m49s
[12:09:57.932] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:23:0)
[12:09:57.951] Build status: STARTED
[12:09:57.951] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:24:0) after 3m24s
[12:13:21.974] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:24:0)
[12:13:21.993] Build status: STARTED
[12:13:21.993] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:25:0) after 5m39s
[12:19:01.012] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:25:0)
[12:19:01.033] Build status: STARTED
[12:19:01.033] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:26:0) after 9m39s
[12:28:40.107] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:26:0)
[12:28:40.132] Build status: STARTED
[12:28:40.132] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:27:0) after 8m16s
[12:36:56.155] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:27:0)
[12:36:56.174] Build status: STARTED
[12:36:56.174] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:28:0) after 7m49s
[12:44:45.315] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:28:0)
[12:44:45.460] Build status: STARTED
[12:44:45.460] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:29:0) after 3m47s
[12:48:32.519] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:29:0)
[12:48:32.557] Build status: STARTED
[12:48:32.557] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:30:0) after 8m50s
[12:57:22.583] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:30:0)
[12:57:22.602] Build status: STARTED
[12:57:22.602] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:31:0) after 1m11s
[12:58:33.646] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:31:0)
[12:58:33.675] Build status: STARTED
[12:58:33.675] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:32:0) after 6m4s
[13:04:37.702] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:32:0)
[13:04:37.729] Build status: STARTED
[13:04:37.729] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:33:0) after 6m12s
[13:10:49.755] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:33:0)
[13:10:49.777] Build status: STARTED
[13:10:49.777] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:34:0) after 7m45s
[13:18:34.808] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:34:0)
[13:18:34.826] Build status: STARTED
[13:18:34.826] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8876619466582460224:35:0) after 1m42s
[13:19:59.218] Received PubSub notification, asking Buildbucket for the build status
[13:19:59.275] Build:
{
"id": "8711189404589687585",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "win32-official"
},
"number": 346,
"createdBy": "project:chromium-m137",
"createTime": "2025-06-24T10:03:41.700163818Z",
"startTime": "2025-06-24T10:03:46.551473438Z",
"endTime": "2025-06-24T13:19:58.873733220Z",
"updateTime": "2025-06-24T13:19:58.873733220Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "062ada5abb0f541b14e39cab611201ef84688eb0",
"ref": "refs/branch-heads/7151"
}
},
"canary": true
}
[13:19:59.275] Invocation finished in 3h16m18.859128707s with status SUCCEEDED