[20:59:22.886] New invocation is queued and will start shortly
[20:59:24.305] Starting the invocation (attempt 1)
[20:59:24.344] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ddcd8f4d5a8aa3a4395ba61b8759549d83646270"
[20:59:24.344] Popped gitiles commit info from properties and tags
[20:59:24.344] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:59:24.345] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:59:24.345] Buildbucket request:
{
"requestId": "8868243292117428272",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8868243292117428272",
"job": "chromium-m140/win-arm64-rel",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e8adff5d330407d319b962981593142ec4e97933"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@e8adff5d330407d319b962981593142ec4e97933",
"title": "e8adff5d330407d319b962981593142ec4e97933",
"url": "https://chromium.googlesource.com/chromium/src/+/e8adff5d330407d319b962981593142ec4e97933"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "dfdc7ea9fc17e22f57ddc6cafbe47054e29d5e09"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@dfdc7ea9fc17e22f57ddc6cafbe47054e29d5e09",
"title": "dfdc7ea9fc17e22f57ddc6cafbe47054e29d5e09",
"url": "https://chromium.googlesource.com/chromium/src/+/dfdc7ea9fc17e22f57ddc6cafbe47054e29d5e09"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ec2a39ef157252ed719fa92603bf6796bd0a6fed"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@ec2a39ef157252ed719fa92603bf6796bd0a6fed",
"title": "ec2a39ef157252ed719fa92603bf6796bd0a6fed",
"url": "https://chromium.googlesource.com/chromium/src/+/ec2a39ef157252ed719fa92603bf6796bd0a6fed"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "06563159f1577a371b4f4aed258d935fa70db423"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@06563159f1577a371b4f4aed258d935fa70db423",
"title": "06563159f1577a371b4f4aed258d935fa70db423",
"url": "https://chromium.googlesource.com/chromium/src/+/06563159f1577a371b4f4aed258d935fa70db423"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7b870e8715cb876da8cd09e33d157e440f34aadc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@7b870e8715cb876da8cd09e33d157e440f34aadc",
"title": "7b870e8715cb876da8cd09e33d157e440f34aadc",
"url": "https://chromium.googlesource.com/chromium/src/+/7b870e8715cb876da8cd09e33d157e440f34aadc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f8ab8c51860a10f7a5c91ffe1a044b674d94db95"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@f8ab8c51860a10f7a5c91ffe1a044b674d94db95",
"title": "f8ab8c51860a10f7a5c91ffe1a044b674d94db95",
"url": "https://chromium.googlesource.com/chromium/src/+/f8ab8c51860a10f7a5c91ffe1a044b674d94db95"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ddcd8f4d5a8aa3a4395ba61b8759549d83646270"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@ddcd8f4d5a8aa3a4395ba61b8759549d83646270",
"title": "ddcd8f4d5a8aa3a4395ba61b8759549d83646270",
"url": "https://chromium.googlesource.com/chromium/src/+/ddcd8f4d5a8aa3a4395ba61b8759549d83646270"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ddcd8f4d5a8aa3a4395ba61b8759549d83646270",
"ref": "refs/branch-heads/7339"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8868243292117428272"
},
{
"key": "scheduler_job_id",
"value": "chromium-m140/win-arm64-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:59:24.977] Scheduled build:
{
"id": "8702813229864174113",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 521,
"createdBy": "project:chromium-m140",
"createTime": "2025-09-24T20:59:24.406403244Z",
"updateTime": "2025-09-24T20:59:24.406403244Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ddcd8f4d5a8aa3a4395ba61b8759549d83646270",
"ref": "refs/branch-heads/7339"
}
},
"canary": true
}
[20:59:24.977] Task URL: https://cr-buildbucket.appspot.com/build/8702813229864174113
[20:59:24.977] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:2:0) after 6m5s
[20:59:26.214] Received PubSub notification, asking Buildbucket for the build status
[20:59:26.245] Build status: SCHEDULED
[20:59:32.698] Received PubSub notification, asking Buildbucket for the build status
[20:59:32.737] Build status: STARTED
[21:05:30.044] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:2:0)
[21:05:30.076] Build status: STARTED
[21:05:30.076] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:5:0) after 2m42s
[21:08:12.189] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:5:0)
[21:08:12.234] Build status: STARTED
[21:08:12.234] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:6:0) after 7m25s
[21:15:37.298] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:6:0)
[21:15:37.329] Build status: STARTED
[21:15:37.329] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:7:0) after 2m20s
[21:17:57.360] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:7:0)
[21:17:57.385] Build status: STARTED
[21:17:57.385] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:8:0) after 4m40s
[21:22:37.509] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:8:0)
[21:22:37.537] Build status: STARTED
[21:22:37.537] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:9:0) after 9m44s
[21:32:21.550] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:9:0)
[21:32:21.570] Build status: STARTED
[21:32:21.570] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:10:0) after 4m53s
[21:37:14.588] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:10:0)
[21:37:14.630] Build status: STARTED
[21:37:14.630] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:11:0) after 4m27s
[21:41:41.646] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:11:0)
[21:41:41.677] Build status: STARTED
[21:41:41.677] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:12:0) after 8m19s
[21:50:00.694] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:12:0)
[21:50:00.709] Build status: STARTED
[21:50:00.709] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:13:0) after 3m55s
[21:53:55.762] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:13:0)
[21:53:55.913] Build status: STARTED
[21:53:55.913] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:14:0) after 7m59s
[22:01:54.937] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:14:0)
[22:01:54.983] Build status: STARTED
[22:01:54.983] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:15:0) after 7m22s
[22:09:16.999] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:15:0)
[22:09:17.016] Build status: STARTED
[22:09:17.017] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:16:0) after 5m55s
[22:15:12.371] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:16:0)
[22:15:12.394] Build status: STARTED
[22:15:12.394] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:17:0) after 5m5s
[22:20:17.478] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:17:0)
[22:20:17.511] Build status: STARTED
[22:20:17.511] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:18:0) after 2m13s
[22:22:30.691] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:18:0)
[22:22:30.711] Build status: STARTED
[22:22:30.711] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:19:0) after 8m2s
[22:30:32.693] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:19:0)
[22:30:32.814] Build status: STARTED
[22:30:32.814] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:20:0) after 2m27s
[22:32:59.830] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:20:0)
[22:32:59.850] Build status: STARTED
[22:32:59.850] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:21:0) after 2m16s
[22:35:15.896] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:21:0)
[22:35:15.914] Build status: STARTED
[22:35:15.914] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:22:0) after 1m24s
[22:36:39.932] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:22:0)
[22:36:39.948] Build status: STARTED
[22:36:39.948] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:23:0) after 8m41s
[22:45:20.965] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:23:0)
[22:45:20.993] Build status: STARTED
[22:45:20.993] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868243292117428272:24:0) after 8m54s
[22:50:51.577] Received PubSub notification, asking Buildbucket for the build status
[22:50:51.594] Build:
{
"id": "8702813229864174113",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 521,
"createdBy": "project:chromium-m140",
"createTime": "2025-09-24T20:59:24.406403244Z",
"startTime": "2025-09-24T20:59:30.694928877Z",
"endTime": "2025-09-24T22:50:51.327705965Z",
"updateTime": "2025-09-24T22:50:51.327705965Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ddcd8f4d5a8aa3a4395ba61b8759549d83646270",
"ref": "refs/branch-heads/7339"
}
},
"canary": true
}
[22:50:51.594] Invocation finished in 1h51m28.721709296s with status SUCCEEDED