[20:54:35.042] New invocation is queued and will start shortly
[20:54:36.187] Starting the invocation (attempt 1)
[20:54:36.208] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/728575c550f51a0b19ea84820ee6f6579cd4cb15"
[20:54:36.208] Popped gitiles commit info from properties and tags
[20:54:36.208] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:54:36.208] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:54:36.208] Buildbucket request:
{
"requestId": "8877031499674463984",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8877031499674463984",
"job": "chromium-m138/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a7e2957cbd883e420bc7c4f19ce223683268d073"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@a7e2957cbd883e420bc7c4f19ce223683268d073",
"title": "a7e2957cbd883e420bc7c4f19ce223683268d073",
"url": "https://chromium.googlesource.com/chromium/src/+/a7e2957cbd883e420bc7c4f19ce223683268d073"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "accb0a86a6c0668c1e0fcbe3501c30425ef084ca"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@accb0a86a6c0668c1e0fcbe3501c30425ef084ca",
"title": "accb0a86a6c0668c1e0fcbe3501c30425ef084ca",
"url": "https://chromium.googlesource.com/chromium/src/+/accb0a86a6c0668c1e0fcbe3501c30425ef084ca"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "381ab7a9f9842513d04af7d4627be80a019a3259"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@381ab7a9f9842513d04af7d4627be80a019a3259",
"title": "381ab7a9f9842513d04af7d4627be80a019a3259",
"url": "https://chromium.googlesource.com/chromium/src/+/381ab7a9f9842513d04af7d4627be80a019a3259"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bf5f1dce6850f871b952d1620b28936b2666a1ff"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@bf5f1dce6850f871b952d1620b28936b2666a1ff",
"title": "bf5f1dce6850f871b952d1620b28936b2666a1ff",
"url": "https://chromium.googlesource.com/chromium/src/+/bf5f1dce6850f871b952d1620b28936b2666a1ff"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1cc446ae1fcf6eedfdf00f63f259632c0b1c0828"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@1cc446ae1fcf6eedfdf00f63f259632c0b1c0828",
"title": "1cc446ae1fcf6eedfdf00f63f259632c0b1c0828",
"url": "https://chromium.googlesource.com/chromium/src/+/1cc446ae1fcf6eedfdf00f63f259632c0b1c0828"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "728575c550f51a0b19ea84820ee6f6579cd4cb15"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@728575c550f51a0b19ea84820ee6f6579cd4cb15",
"title": "728575c550f51a0b19ea84820ee6f6579cd4cb15",
"url": "https://chromium.googlesource.com/chromium/src/+/728575c550f51a0b19ea84820ee6f6579cd4cb15"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "728575c550f51a0b19ea84820ee6f6579cd4cb15",
"ref": "refs/branch-heads/7204"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8877031499674463984"
},
{
"key": "scheduler_job_id",
"value": "chromium-m138/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:54:36.866] Scheduled build:
{
"id": "8711601437743864561",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "win32-official"
},
"number": 157,
"createdBy": "project:chromium-m138",
"createTime": "2025-06-19T20:54:36.263196014Z",
"updateTime": "2025-06-19T20:54:36.263196014Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "728575c550f51a0b19ea84820ee6f6579cd4cb15",
"ref": "refs/branch-heads/7204"
}
}
}
[20:54:36.866] Task URL: https://cr-buildbucket.appspot.com/build/8711601437743864561
[20:54:36.866] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:2:0) after 9m6s
[20:54:37.702] Received PubSub notification, asking Buildbucket for the build status
[20:54:37.725] Build status: SCHEDULED
[20:55:58.228] Received PubSub notification, asking Buildbucket for the build status
[20:55:58.334] Build status: STARTED
[21:03:42.906] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:2:0)
[21:03:42.939] Build status: STARTED
[21:03:42.939] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:5:0) after 5m29s
[21:09:12.060] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:5:0)
[21:09:12.081] Build status: STARTED
[21:09:12.081] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:6:0) after 7m50s
[21:17:02.122] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:6:0)
[21:17:02.146] Build status: STARTED
[21:17:02.146] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:7:0) after 5m1s
[21:22:03.165] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:7:0)
[21:22:03.188] Build status: STARTED
[21:22:03.188] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:8:0) after 4m26s
[21:26:29.308] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:8:0)
[21:26:29.388] Build status: STARTED
[21:26:29.388] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:9:0) after 2m51s
[21:29:20.620] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:9:0)
[21:29:20.645] Build status: STARTED
[21:29:20.645] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:10:0) after 7m10s
[21:36:30.668] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:10:0)
[21:36:30.700] Build status: STARTED
[21:36:30.700] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:11:0) after 1m53s
[21:38:23.914] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:11:0)
[21:38:23.940] Build status: STARTED
[21:38:23.940] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:12:0) after 8m27s
[21:46:50.991] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:12:0)
[21:46:51.017] Build status: STARTED
[21:46:51.017] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:13:0) after 7m2s
[21:53:53.033] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:13:0)
[21:53:53.103] Build status: STARTED
[21:53:53.103] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:14:0) after 9m3s
[22:02:56.092] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:14:0)
[22:02:56.118] Build status: STARTED
[22:02:56.118] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:15:0) after 5m32s
[22:08:28.121] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:15:0)
[22:08:28.169] Build status: STARTED
[22:08:28.169] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:16:0) after 5m55s
[22:14:23.230] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:16:0)
[22:14:23.255] Build status: STARTED
[22:14:23.256] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:17:0) after 6m52s
[22:21:15.416] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:17:0)
[22:21:15.443] Build status: STARTED
[22:21:15.443] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:18:0) after 9m43s
[22:30:58.704] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:18:0)
[22:30:58.839] Build status: STARTED
[22:30:58.839] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:19:0) after 6m59s
[22:37:57.861] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:19:0)
[22:37:57.883] Build status: STARTED
[22:37:57.884] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:20:0) after 6m44s
[22:44:41.998] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:20:0)
[22:44:42.057] Build status: STARTED
[22:44:42.057] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:21:0) after 7m38s
[22:52:20.177] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:21:0)
[22:52:20.200] Build status: STARTED
[22:52:20.200] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:22:0) after 8m16s
[23:00:36.200] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:22:0)
[23:00:36.229] Build status: STARTED
[23:00:36.229] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:23:0) after 6m19s
[23:06:55.333] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:23:0)
[23:06:55.352] Build status: STARTED
[23:06:55.352] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:24:0) after 9m25s
[23:16:20.378] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:24:0)
[23:16:20.405] Build status: STARTED
[23:16:20.405] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:25:0) after 1m19s
[23:17:39.426] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:25:0)
[23:17:39.441] Build status: STARTED
[23:17:39.441] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:26:0) after 4m53s
[23:22:32.420] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:26:0)
[23:22:32.437] Build status: STARTED
[23:22:32.437] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:27:0) after 7m57s
[23:30:29.459] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:27:0)
[23:30:29.476] Build status: STARTED
[23:30:29.476] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:28:0) after 8m36s
[23:39:05.501] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:28:0)
[23:39:05.586] Build status: STARTED
[23:39:05.586] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:29:0) after 4m23s
[23:43:29.047] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:29:0)
[23:43:29.069] Build status: STARTED
[23:43:29.069] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:30:0) after 2m22s
[23:45:51.165] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:30:0)
[23:45:51.184] Build status: STARTED
[23:45:51.184] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:31:0) after 8m15s
[23:54:06.224] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:31:0)
[23:54:06.243] Build status: STARTED
[23:54:06.243] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:32:0) after 5m32s
[23:59:38.367] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:32:0)
[23:59:38.384] Build status: STARTED
[23:59:38.384] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877031499674463984:33:0) after 6m18s
[00:01:13.224] Received PubSub notification, asking Buildbucket for the build status
[00:01:13.300] Build:
{
"id": "8711601437743864561",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "win32-official"
},
"number": 157,
"createdBy": "project:chromium-m138",
"createTime": "2025-06-19T20:54:36.263196014Z",
"startTime": "2025-06-19T20:55:58.041370389Z",
"endTime": "2025-06-20T00:01:11.228398913Z",
"updateTime": "2025-06-20T00:01:11.228398913Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "728575c550f51a0b19ea84820ee6f6579cd4cb15",
"ref": "refs/branch-heads/7204"
}
}
}
[00:01:13.300] Invocation finished in 3h6m38.260942172s with status SUCCEEDED