[00:49:01.191] New invocation is queued and will start shortly
[00:49:02.762] Starting the invocation (attempt 1)
[00:49:02.789] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ad289b77212dffe00bbf75710c717d73c4a87be"
[00:49:02.789] Popped gitiles commit info from properties and tags
[00:49:02.789] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:49:02.790] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:49:02.790] Buildbucket request:
{
"requestId": "8884355104535520352",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8884355104535520352",
"job": "chromium-m135/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "74a43b31c4e0f56b0466654f104cbd632384dd8a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@74a43b31c4e0f56b0466654f104cbd632384dd8a",
"title": "74a43b31c4e0f56b0466654f104cbd632384dd8a",
"url": "https://chromium.googlesource.com/chromium/src/+/74a43b31c4e0f56b0466654f104cbd632384dd8a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "364dc2021c4125257bf98143d64d34da48a83a7e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@364dc2021c4125257bf98143d64d34da48a83a7e",
"title": "364dc2021c4125257bf98143d64d34da48a83a7e",
"url": "https://chromium.googlesource.com/chromium/src/+/364dc2021c4125257bf98143d64d34da48a83a7e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "63bc79001b7a9b72a8fa6c08a8d6119a010b2045"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@63bc79001b7a9b72a8fa6c08a8d6119a010b2045",
"title": "63bc79001b7a9b72a8fa6c08a8d6119a010b2045",
"url": "https://chromium.googlesource.com/chromium/src/+/63bc79001b7a9b72a8fa6c08a8d6119a010b2045"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "56ce9c048e0d7352c68b70199d6f0140e64d4451"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@56ce9c048e0d7352c68b70199d6f0140e64d4451",
"title": "56ce9c048e0d7352c68b70199d6f0140e64d4451",
"url": "https://chromium.googlesource.com/chromium/src/+/56ce9c048e0d7352c68b70199d6f0140e64d4451"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7e706696c18414ff30376110ef1f8abb01e3c2e9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@7e706696c18414ff30376110ef1f8abb01e3c2e9",
"title": "7e706696c18414ff30376110ef1f8abb01e3c2e9",
"url": "https://chromium.googlesource.com/chromium/src/+/7e706696c18414ff30376110ef1f8abb01e3c2e9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0ad289b77212dffe00bbf75710c717d73c4a87be"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@0ad289b77212dffe00bbf75710c717d73c4a87be",
"title": "0ad289b77212dffe00bbf75710c717d73c4a87be",
"url": "https://chromium.googlesource.com/chromium/src/+/0ad289b77212dffe00bbf75710c717d73c4a87be"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0ad289b77212dffe00bbf75710c717d73c4a87be",
"ref": "refs/branch-heads/7049"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8884355104535520352"
},
{
"key": "scheduler_job_id",
"value": "chromium-m135/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:49:03.332] Scheduled build:
{
"id": "8718925042137984257",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "win-official"
},
"number": 227,
"createdBy": "project:chromium-m135",
"createTime": "2025-03-31T00:49:02.849767413Z",
"updateTime": "2025-03-31T00:49:02.849767413Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0ad289b77212dffe00bbf75710c717d73c4a87be",
"ref": "refs/branch-heads/7049"
}
}
}
[00:49:03.332] Task URL: https://cr-buildbucket.appspot.com/build/8718925042137984257
[00:49:03.332] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:2:0) after 2m10s
[00:49:04.347] Received PubSub notification, asking Buildbucket for the build status
[00:49:04.372] Build status: SCHEDULED
[00:49:08.697] Received PubSub notification, asking Buildbucket for the build status
[00:49:08.716] Build status: STARTED
[00:51:13.299] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:2:0)
[00:51:13.324] Build status: STARTED
[00:51:13.324] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:5:0) after 9m17s
[01:00:30.607] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:5:0)
[01:00:30.632] Build status: STARTED
[01:00:30.632] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:6:0) after 8m45s
[01:09:15.680] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:6:0)
[01:09:15.699] Build status: STARTED
[01:09:15.699] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:7:0) after 8m25s
[01:17:40.716] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:7:0)
[01:17:40.777] Build status: STARTED
[01:17:40.777] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:8:0) after 7m5s
[01:24:45.895] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:8:0)
[01:24:45.989] Build status: STARTED
[01:24:45.989] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:9:0) after 6m56s
[01:31:42.023] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:9:0)
[01:31:42.048] Build status: STARTED
[01:31:42.048] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:10:0) after 1m31s
[01:33:13.258] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:10:0)
[01:33:13.311] Build status: STARTED
[01:33:13.311] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:11:0) after 4m33s
[01:37:46.378] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:11:0)
[01:37:46.439] Build status: STARTED
[01:37:46.440] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:12:0) after 9m59s
[01:47:45.454] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:12:0)
[01:47:45.478] Build status: STARTED
[01:47:45.478] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:13:0) after 5m25s
[01:53:10.640] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:13:0)
[01:53:10.739] Build status: STARTED
[01:53:10.739] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:14:0) after 3m59s
[01:57:09.761] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:14:0)
[01:57:09.784] Build status: STARTED
[01:57:09.784] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:15:0) after 2m11s
[01:59:20.808] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:15:0)
[01:59:20.833] Build status: STARTED
[01:59:20.833] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:16:0) after 7m44s
[02:07:04.908] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:16:0)
[02:07:04.933] Build status: STARTED
[02:07:04.933] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:17:0) after 2m4s
[02:09:09.041] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:17:0)
[02:09:09.068] Build status: STARTED
[02:09:09.068] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:18:0) after 6m2s
[02:15:11.184] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:18:0)
[02:15:11.268] Build status: STARTED
[02:15:11.268] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:19:0) after 1m10s
[02:16:21.417] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:19:0)
[02:16:21.458] Build status: STARTED
[02:16:21.458] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:20:0) after 7m5s
[02:23:26.621] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:20:0)
[02:23:26.660] Build status: STARTED
[02:23:26.660] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:21:0) after 1m21s
[02:24:47.680] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:21:0)
[02:24:47.705] Build status: STARTED
[02:24:47.705] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:22:0) after 2m48s
[02:27:35.942] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:22:0)
[02:27:35.974] Build status: STARTED
[02:27:35.974] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:23:0) after 8m19s
[02:35:55.564] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:23:0)
[02:35:55.596] Build status: STARTED
[02:35:55.596] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:24:0) after 1m10s
[02:37:05.702] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:24:0)
[02:37:05.728] Build status: STARTED
[02:37:05.728] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:25:0) after 6m43s
[02:43:48.749] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:25:0)
[02:43:48.774] Build status: STARTED
[02:43:48.774] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:26:0) after 8m34s
[02:52:22.866] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:26:0)
[02:52:22.889] Build status: STARTED
[02:52:22.889] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:27:0) after 7m59s
[03:00:21.887] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:27:0)
[03:00:21.909] Build status: STARTED
[03:00:21.909] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:28:0) after 4m55s
[03:05:17.025] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:28:0)
[03:05:17.049] Build status: STARTED
[03:05:17.049] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:29:0) after 7m0s
[03:12:17.077] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:29:0)
[03:12:17.096] Build status: STARTED
[03:12:17.096] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:30:0) after 7m12s
[03:19:29.187] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:30:0)
[03:19:29.209] Build status: STARTED
[03:19:29.209] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:31:0) after 2m44s
[03:22:13.445] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:31:0)
[03:22:13.470] Build status: STARTED
[03:22:13.470] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:32:0) after 2m18s
[03:24:31.615] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:32:0)
[03:24:31.634] Build status: STARTED
[03:24:31.634] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:33:0) after 7m35s
[03:32:06.684] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:33:0)
[03:32:06.706] Build status: STARTED
[03:32:06.706] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:34:0) after 1m41s
[03:33:47.838] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:34:0)
[03:33:47.860] Build status: STARTED
[03:33:47.860] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:35:0) after 5m22s
[03:39:10.061] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:35:0)
[03:39:10.088] Build status: STARTED
[03:39:10.088] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:36:0) after 2m33s
[03:41:43.117] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:36:0)
[03:41:43.175] Build status: STARTED
[03:41:43.175] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:37:0) after 9m28s
[03:51:11.379] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:37:0)
[03:51:11.395] Build status: STARTED
[03:51:11.395] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8884355104535520352:38:0) after 8m41s
[03:51:26.786] Received PubSub notification, asking Buildbucket for the build status
[03:51:26.831] Build:
{
"id": "8718925042137984257",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "win-official"
},
"number": 227,
"createdBy": "project:chromium-m135",
"createTime": "2025-03-31T00:49:02.849767413Z",
"startTime": "2025-03-31T00:49:08.441098488Z",
"endTime": "2025-03-31T03:51:26.483156763Z",
"updateTime": "2025-03-31T03:51:26.483156763Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0ad289b77212dffe00bbf75710c717d73c4a87be",
"ref": "refs/branch-heads/7049"
}
}
}
[03:51:26.831] Invocation finished in 3h2m25.652500124s with status SUCCEEDED