[21:08:53.317] New invocation is queued and will start shortly
[21:08:54.371] Starting the invocation (attempt 1)
[21:08:54.407] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9264fa161bb9270e5a0763c8832b6dce7a6a39c6"
[21:08:54.407] Popped gitiles commit info from properties and tags
[21:08:54.408] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:08:54.408] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:08:54.408] Buildbucket request:
{
"requestId": "8848039570459327408",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8848039570459327408",
"job": "chromium-m148/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bdeadca69404ef6e2ddb80ab452ec7fb8877db2b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@bdeadca69404ef6e2ddb80ab452ec7fb8877db2b",
"title": "bdeadca69404ef6e2ddb80ab452ec7fb8877db2b",
"url": "https://chromium.googlesource.com/chromium/src/+/bdeadca69404ef6e2ddb80ab452ec7fb8877db2b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "566beaff2ff90c44a8f9e63994d66f5b64433d2d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@566beaff2ff90c44a8f9e63994d66f5b64433d2d",
"title": "566beaff2ff90c44a8f9e63994d66f5b64433d2d",
"url": "https://chromium.googlesource.com/chromium/src/+/566beaff2ff90c44a8f9e63994d66f5b64433d2d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "11248b41d581d682db68d0c9294bbd7599fa2621"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@11248b41d581d682db68d0c9294bbd7599fa2621",
"title": "11248b41d581d682db68d0c9294bbd7599fa2621",
"url": "https://chromium.googlesource.com/chromium/src/+/11248b41d581d682db68d0c9294bbd7599fa2621"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5166ecf8ccaa34704a082dbac6bc81f8baad99e5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@5166ecf8ccaa34704a082dbac6bc81f8baad99e5",
"title": "5166ecf8ccaa34704a082dbac6bc81f8baad99e5",
"url": "https://chromium.googlesource.com/chromium/src/+/5166ecf8ccaa34704a082dbac6bc81f8baad99e5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "85b9364365cabee013e0174fd761fcee4786d93d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@85b9364365cabee013e0174fd761fcee4786d93d",
"title": "85b9364365cabee013e0174fd761fcee4786d93d",
"url": "https://chromium.googlesource.com/chromium/src/+/85b9364365cabee013e0174fd761fcee4786d93d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "696cd3ce26a4e515a1ce0f7b5b8043ce5b3244b4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@696cd3ce26a4e515a1ce0f7b5b8043ce5b3244b4",
"title": "696cd3ce26a4e515a1ce0f7b5b8043ce5b3244b4",
"url": "https://chromium.googlesource.com/chromium/src/+/696cd3ce26a4e515a1ce0f7b5b8043ce5b3244b4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "69ab869f389508336983dcdb8a63e87c58e03be1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@69ab869f389508336983dcdb8a63e87c58e03be1",
"title": "69ab869f389508336983dcdb8a63e87c58e03be1",
"url": "https://chromium.googlesource.com/chromium/src/+/69ab869f389508336983dcdb8a63e87c58e03be1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "73b789e83396e11846397e86bba3879d762877f1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@73b789e83396e11846397e86bba3879d762877f1",
"title": "73b789e83396e11846397e86bba3879d762877f1",
"url": "https://chromium.googlesource.com/chromium/src/+/73b789e83396e11846397e86bba3879d762877f1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "903bc981ea074f0c278fdc23bea8d15bdd1a8d7f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@903bc981ea074f0c278fdc23bea8d15bdd1a8d7f",
"title": "903bc981ea074f0c278fdc23bea8d15bdd1a8d7f",
"url": "https://chromium.googlesource.com/chromium/src/+/903bc981ea074f0c278fdc23bea8d15bdd1a8d7f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a27012e579afa80b592bcef445f3e78f4c09bd76"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@a27012e579afa80b592bcef445f3e78f4c09bd76",
"title": "a27012e579afa80b592bcef445f3e78f4c09bd76",
"url": "https://chromium.googlesource.com/chromium/src/+/a27012e579afa80b592bcef445f3e78f4c09bd76"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9264fa161bb9270e5a0763c8832b6dce7a6a39c6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@9264fa161bb9270e5a0763c8832b6dce7a6a39c6",
"title": "9264fa161bb9270e5a0763c8832b6dce7a6a39c6",
"url": "https://chromium.googlesource.com/chromium/src/+/9264fa161bb9270e5a0763c8832b6dce7a6a39c6"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9264fa161bb9270e5a0763c8832b6dce7a6a39c6",
"ref": "refs/branch-heads/7778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8848039570459327408"
},
{
"key": "scheduler_job_id",
"value": "chromium-m148/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:08:55.003] Scheduled build:
{
"id": "8682609508602292145",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "win32-official"
},
"number": 159,
"createdBy": "project:chromium-m148",
"createTime": "2026-05-05T21:08:54.469190286Z",
"updateTime": "2026-05-05T21:08:54.469190286Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9264fa161bb9270e5a0763c8832b6dce7a6a39c6",
"ref": "refs/branch-heads/7778"
}
}
}
[21:08:55.003] Task URL: https://cr-buildbucket.appspot.com/build/8682609508602292145
[21:08:55.003] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:2:0) after 8m0s
[21:08:55.908] Received PubSub notification, asking Buildbucket for the build status
[21:08:55.923] Build status: SCHEDULED
[21:08:59.439] Received PubSub notification, asking Buildbucket for the build status
[21:08:59.481] Build status: STARTED
[21:16:55.001] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:2:0)
[21:16:55.026] Build status: STARTED
[21:16:55.026] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:5:0) after 5m1s
[21:21:56.220] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:5:0)
[21:21:56.238] Build status: STARTED
[21:21:56.238] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:6:0) after 2m41s
[21:24:37.391] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:6:0)
[21:24:37.416] Build status: STARTED
[21:24:37.416] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:7:0) after 3m27s
[21:28:04.431] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:7:0)
[21:28:04.452] Build status: STARTED
[21:28:04.452] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:8:0) after 7m15s
[21:35:19.469] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:8:0)
[21:35:19.502] Build status: STARTED
[21:35:19.502] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:9:0) after 3m54s
[21:39:13.586] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:9:0)
[21:39:13.627] Build status: STARTED
[21:39:13.627] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:10:0) after 5m37s
[21:44:50.652] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:10:0)
[21:44:50.672] Build status: STARTED
[21:44:50.672] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:11:0) after 3m51s
[21:48:41.704] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:11:0)
[21:48:41.723] Build status: STARTED
[21:48:41.723] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:12:0) after 3m28s
[21:52:10.026] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:12:0)
[21:52:10.045] Build status: STARTED
[21:52:10.045] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:13:0) after 7m42s
[21:59:52.155] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:13:0)
[21:59:52.180] Build status: STARTED
[21:59:52.180] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:14:0) after 9m53s
[22:09:45.175] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:14:0)
[22:09:45.194] Build status: STARTED
[22:09:45.194] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:15:0) after 6m0s
[22:15:45.217] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:15:0)
[22:15:45.230] Build status: STARTED
[22:15:45.230] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:16:0) after 1m51s
[22:17:36.293] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:16:0)
[22:17:36.314] Build status: STARTED
[22:17:36.314] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:17:0) after 8m54s
[22:26:30.284] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:17:0)
[22:26:30.303] Build status: STARTED
[22:26:30.303] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:18:0) after 4m6s
[22:30:36.311] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:18:0)
[22:30:36.331] Build status: STARTED
[22:30:36.331] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:19:0) after 5m5s
[22:35:41.380] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:19:0)
[22:35:41.397] Build status: STARTED
[22:35:41.397] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:20:0) after 8m51s
[22:44:32.411] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:20:0)
[22:44:32.428] Build status: STARTED
[22:44:32.428] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:21:0) after 1m58s
[22:46:30.485] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:21:0)
[22:46:30.508] Build status: STARTED
[22:46:30.509] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:22:0) after 2m40s
[22:49:10.538] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:22:0)
[22:49:10.568] Build status: STARTED
[22:49:10.568] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:23:0) after 2m43s
[22:51:53.618] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:23:0)
[22:51:53.650] Build status: STARTED
[22:51:53.650] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:24:0) after 1m23s
[22:53:16.684] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:24:0)
[22:53:16.707] Build status: STARTED
[22:53:16.708] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:25:0) after 8m32s
[23:01:48.752] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:25:0)
[23:01:48.780] Build status: STARTED
[23:01:48.780] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:26:0) after 8m57s
[23:10:46.203] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:26:0)
[23:10:46.228] Build status: STARTED
[23:10:46.229] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:27:0) after 6m12s
[23:16:58.278] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:27:0)
[23:16:58.296] Build status: STARTED
[23:16:58.296] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:28:0) after 9m15s
[23:26:13.404] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:28:0)
[23:26:13.428] Build status: STARTED
[23:26:13.428] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:29:0) after 9m2s
[23:35:15.580] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:29:0)
[23:35:15.601] Build status: STARTED
[23:35:15.601] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:30:0) after 6m8s
[23:41:23.650] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:30:0)
[23:41:23.673] Build status: STARTED
[23:41:23.673] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:31:0) after 4m56s
[23:46:19.722] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:31:0)
[23:46:19.740] Build status: STARTED
[23:46:19.740] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:32:0) after 8m24s
[23:54:43.706] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:32:0)
[23:54:43.732] Build status: STARTED
[23:54:43.732] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:33:0) after 3m22s
[23:58:05.754] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:33:0)
[23:58:05.776] Build status: STARTED
[23:58:05.776] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:34:0) after 4m57s
[00:03:02.893] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:34:0)
[00:03:02.910] Build status: STARTED
[00:03:02.910] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:35:0) after 7m24s
[00:10:26.949] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:35:0)
[00:10:26.969] Build status: STARTED
[00:10:26.969] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:36:0) after 9m17s
[00:19:43.936] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:36:0)
[00:19:43.952] Build status: STARTED
[00:19:43.952] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:37:0) after 5m47s
[00:25:31.093] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:37:0)
[00:25:31.116] Build status: STARTED
[00:25:31.116] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:38:0) after 1m21s
[00:26:52.201] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:38:0)
[00:26:52.242] Build status: STARTED
[00:26:52.242] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:39:0) after 8m33s
[00:35:25.331] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:39:0)
[00:35:25.354] Build status: STARTED
[00:35:25.354] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:40:0) after 5m5s
[00:40:30.595] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:40:0)
[00:40:30.618] Build status: STARTED
[00:40:30.618] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:41:0) after 8m39s
[00:49:09.771] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:41:0)
[00:49:09.825] Build status: STARTED
[00:49:09.825] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:42:0) after 1m17s
[00:50:26.940] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:42:0)
[00:50:26.960] Build status: STARTED
[00:50:26.960] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:43:0) after 9m17s
[00:59:44.028] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:43:0)
[00:59:44.042] Build status: STARTED
[00:59:44.042] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:44:0) after 4m29s
[01:04:13.231] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:44:0)
[01:04:13.308] Build status: STARTED
[01:04:13.308] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8848039570459327408:45:0) after 9m10s
[01:06:35.356] Received PubSub notification, asking Buildbucket for the build status
[01:06:35.375] Build:
{
"id": "8682609508602292145",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "win32-official"
},
"number": 159,
"createdBy": "project:chromium-m148",
"createTime": "2026-05-05T21:08:54.469190286Z",
"startTime": "2026-05-05T21:08:59.250242611Z",
"endTime": "2026-05-06T01:06:34.894699136Z",
"updateTime": "2026-05-06T01:06:34.894699136Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9264fa161bb9270e5a0763c8832b6dce7a6a39c6",
"ref": "refs/branch-heads/7778"
}
}
}
[01:06:35.375] Invocation finished in 3h57m42.060606235s with status SUCCEEDED