[11:30:31.424] New invocation is queued and will start shortly
[11:30:32.526] Starting the invocation (attempt 1)
[11:30:32.615] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ccf9c30022adf3ff02b4d77fb7fce1fcded37d78"
[11:30:32.615] Popped gitiles commit info from properties and tags
[11:30:32.615] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:30:32.615] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:30:32.616] Buildbucket request:
{
"requestId": "8860397145470097984",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8860397145470097984",
"job": "chromium-m144/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b2cdd9c49cf802071fe275e5035c080606916970"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@b2cdd9c49cf802071fe275e5035c080606916970",
"title": "b2cdd9c49cf802071fe275e5035c080606916970",
"url": "https://chromium.googlesource.com/chromium/src/+/b2cdd9c49cf802071fe275e5035c080606916970"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4c95e01c93f7d324f52942b0acd7e1194a33721f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@4c95e01c93f7d324f52942b0acd7e1194a33721f",
"title": "4c95e01c93f7d324f52942b0acd7e1194a33721f",
"url": "https://chromium.googlesource.com/chromium/src/+/4c95e01c93f7d324f52942b0acd7e1194a33721f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d15f92d167e1711fa625cea48a153d586ce4d643"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@d15f92d167e1711fa625cea48a153d586ce4d643",
"title": "d15f92d167e1711fa625cea48a153d586ce4d643",
"url": "https://chromium.googlesource.com/chromium/src/+/d15f92d167e1711fa625cea48a153d586ce4d643"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2e60d7e239795e22b9a828302b886fa8ee1fdd2a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@2e60d7e239795e22b9a828302b886fa8ee1fdd2a",
"title": "2e60d7e239795e22b9a828302b886fa8ee1fdd2a",
"url": "https://chromium.googlesource.com/chromium/src/+/2e60d7e239795e22b9a828302b886fa8ee1fdd2a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d33bf8db2c4d42b5e49e09df7c59f23ae160923d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@d33bf8db2c4d42b5e49e09df7c59f23ae160923d",
"title": "d33bf8db2c4d42b5e49e09df7c59f23ae160923d",
"url": "https://chromium.googlesource.com/chromium/src/+/d33bf8db2c4d42b5e49e09df7c59f23ae160923d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "092d1d2c2013f6c8abc870fe157060f7c0151e2a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@092d1d2c2013f6c8abc870fe157060f7c0151e2a",
"title": "092d1d2c2013f6c8abc870fe157060f7c0151e2a",
"url": "https://chromium.googlesource.com/chromium/src/+/092d1d2c2013f6c8abc870fe157060f7c0151e2a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5db2699292f1416c99a4a2a206289ded17c6a722"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@5db2699292f1416c99a4a2a206289ded17c6a722",
"title": "5db2699292f1416c99a4a2a206289ded17c6a722",
"url": "https://chromium.googlesource.com/chromium/src/+/5db2699292f1416c99a4a2a206289ded17c6a722"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7198d4360b261d62930e3626e74db30683cbe6ff"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@7198d4360b261d62930e3626e74db30683cbe6ff",
"title": "7198d4360b261d62930e3626e74db30683cbe6ff",
"url": "https://chromium.googlesource.com/chromium/src/+/7198d4360b261d62930e3626e74db30683cbe6ff"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "60d958196f123c642d3f7a0cd649aec5d55b54be"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@60d958196f123c642d3f7a0cd649aec5d55b54be",
"title": "60d958196f123c642d3f7a0cd649aec5d55b54be",
"url": "https://chromium.googlesource.com/chromium/src/+/60d958196f123c642d3f7a0cd649aec5d55b54be"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ccf9c30022adf3ff02b4d77fb7fce1fcded37d78"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@ccf9c30022adf3ff02b4d77fb7fce1fcded37d78",
"title": "ccf9c30022adf3ff02b4d77fb7fce1fcded37d78",
"url": "https://chromium.googlesource.com/chromium/src/+/ccf9c30022adf3ff02b4d77fb7fce1fcded37d78"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ccf9c30022adf3ff02b4d77fb7fce1fcded37d78",
"ref": "refs/branch-heads/7559"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8860397145470097984"
},
{
"key": "scheduler_job_id",
"value": "chromium-m144/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:30:33.143] Scheduled build:
{
"id": "8694967083396645457",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win32-official"
},
"number": 112,
"createdBy": "project:chromium-m144",
"createTime": "2025-12-20T11:30:32.774026678Z",
"updateTime": "2025-12-20T11:30:32.774026678Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ccf9c30022adf3ff02b4d77fb7fce1fcded37d78",
"ref": "refs/branch-heads/7559"
}
}
}
[11:30:33.143] Task URL: https://cr-buildbucket.appspot.com/build/8694967083396645457
[11:30:33.143] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:2:0) after 3m41s
[11:30:34.094] Received PubSub notification, asking Buildbucket for the build status
[11:30:34.124] Build status: SCHEDULED
[11:30:37.508] Received PubSub notification, asking Buildbucket for the build status
[11:30:37.579] Build status: STARTED
[11:34:14.186] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:2:0)
[11:34:14.303] Build status: STARTED
[11:34:14.303] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:5:0) after 9m11s
[11:43:25.428] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:5:0)
[11:43:25.453] Build status: STARTED
[11:43:25.454] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:6:0) after 2m20s
[11:45:45.524] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:6:0)
[11:45:45.553] Build status: STARTED
[11:45:45.553] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:7:0) after 8m32s
[11:54:17.529] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:7:0)
[11:54:17.843] Build status: STARTED
[11:54:17.843] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:8:0) after 7m6s
[12:01:23.964] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:8:0)
[12:01:23.981] Build status: STARTED
[12:01:23.981] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:9:0) after 1m30s
[12:02:53.998] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:9:0)
[12:02:54.026] Build status: STARTED
[12:02:54.026] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:10:0) after 1m17s
[12:04:11.040] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:10:0)
[12:04:11.136] Build status: STARTED
[12:04:11.136] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:11:0) after 6m48s
[12:10:59.150] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:11:0)
[12:10:59.184] Build status: STARTED
[12:10:59.184] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:12:0) after 7m55s
[12:18:54.200] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:12:0)
[12:18:54.226] Build status: STARTED
[12:18:54.226] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:13:0) after 7m17s
[12:26:11.244] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:13:0)
[12:26:11.269] Build status: STARTED
[12:26:11.269] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:14:0) after 5m46s
[12:31:57.285] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:14:0)
[12:31:57.308] Build status: STARTED
[12:31:57.308] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:15:0) after 4m18s
[12:36:15.324] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:15:0)
[12:36:15.345] Build status: STARTED
[12:36:15.345] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:16:0) after 7m52s
[12:44:07.423] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:16:0)
[12:44:07.453] Build status: STARTED
[12:44:07.453] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:17:0) after 8m3s
[12:52:10.471] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:17:0)
[12:52:10.498] Build status: STARTED
[12:52:10.498] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:18:0) after 5m58s
[12:58:08.521] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:18:0)
[12:58:08.534] Build status: STARTED
[12:58:08.534] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:19:0) after 7m55s
[13:06:03.553] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:19:0)
[13:06:03.571] Build status: STARTED
[13:06:03.571] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:20:0) after 1m32s
[13:07:35.789] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:20:0)
[13:07:35.803] Build status: STARTED
[13:07:35.803] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:21:0) after 6m8s
[13:13:43.829] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:21:0)
[13:13:43.846] Build status: STARTED
[13:13:43.846] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:22:0) after 4m45s
[13:18:29.028] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:22:0)
[13:18:29.048] Build status: STARTED
[13:18:29.048] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:23:0) after 1m55s
[13:20:24.276] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:23:0)
[13:20:24.297] Build status: STARTED
[13:20:24.297] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:24:0) after 7m33s
[13:27:57.592] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:24:0)
[13:27:57.604] Build status: STARTED
[13:27:57.604] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:25:0) after 3m49s
[13:31:46.618] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:25:0)
[13:31:46.636] Build status: STARTED
[13:31:46.636] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:26:0) after 1m47s
[13:33:33.896] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:26:0)
[13:33:33.919] Build status: STARTED
[13:33:33.919] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:27:0) after 3m9s
[13:36:43.155] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:27:0)
[13:36:43.173] Build status: STARTED
[13:36:43.174] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:28:0) after 4m21s
[13:41:04.143] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:28:0)
[13:41:04.173] Build status: STARTED
[13:41:04.173] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:29:0) after 2m21s
[13:43:25.333] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:29:0)
[13:43:25.393] Build status: STARTED
[13:43:25.393] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:30:0) after 3m49s
[13:47:14.551] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:30:0)
[13:47:14.568] Build status: STARTED
[13:47:14.568] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:31:0) after 4m26s
[13:51:40.669] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:31:0)
[13:51:40.689] Build status: STARTED
[13:51:40.689] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:32:0) after 9m24s
[14:01:04.735] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:32:0)
[14:01:04.790] Build status: STARTED
[14:01:04.791] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:33:0) after 2m42s
[14:03:46.805] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:33:0)
[14:03:46.902] Build status: STARTED
[14:03:46.902] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:34:0) after 1m0s
[14:04:46.923] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:34:0)
[14:04:46.949] Build status: STARTED
[14:04:46.949] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:35:0) after 2m0s
[14:06:46.969] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:35:0)
[14:06:46.983] Build status: STARTED
[14:06:46.983] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:36:0) after 9m40s
[14:16:27.122] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:36:0)
[14:16:27.136] Build status: STARTED
[14:16:27.136] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:37:0) after 2m36s
[14:19:03.184] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:37:0)
[14:19:03.208] Build status: STARTED
[14:19:03.208] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:38:0) after 4m18s
[14:23:21.330] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:38:0)
[14:23:21.347] Build status: STARTED
[14:23:21.348] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:39:0) after 2m38s
[14:25:59.425] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:39:0)
[14:25:59.437] Build status: STARTED
[14:25:59.437] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:40:0) after 9m28s
[14:35:27.730] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:40:0)
[14:35:27.749] Build status: STARTED
[14:35:27.750] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:41:0) after 7m51s
[14:43:18.763] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:41:0)
[14:43:18.778] Build status: STARTED
[14:43:18.778] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:42:0) after 8m58s
[14:52:17.010] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:42:0)
[14:52:17.024] Build status: STARTED
[14:52:17.024] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:43:0) after 1m39s
[14:53:56.019] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:43:0)
[14:53:56.035] Build status: STARTED
[14:53:56.036] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:44:0) after 2m0s
[14:55:56.184] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:44:0)
[14:55:56.211] Build status: STARTED
[14:55:56.211] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:45:0) after 7m31s
[15:03:27.465] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:45:0)
[15:03:27.480] Build status: STARTED
[15:03:27.480] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:46:0) after 6m17s
[15:09:44.558] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:46:0)
[15:09:44.758] Build status: STARTED
[15:09:44.758] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:47:0) after 8m59s
[15:18:43.767] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:47:0)
[15:18:43.787] Build status: STARTED
[15:18:43.787] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8860397145470097984:48:0) after 2m54s
[15:21:24.258] Received PubSub notification, asking Buildbucket for the build status
[15:21:24.280] Build:
{
"id": "8694967083396645457",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win32-official"
},
"number": 112,
"createdBy": "project:chromium-m144",
"createTime": "2025-12-20T11:30:32.774026678Z",
"startTime": "2025-12-20T11:30:37.319292286Z",
"endTime": "2025-12-20T15:21:24.016626168Z",
"updateTime": "2025-12-20T15:21:24.016626168Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ccf9c30022adf3ff02b4d77fb7fce1fcded37d78",
"ref": "refs/branch-heads/7559"
}
}
}
[15:21:24.280] Invocation finished in 3h50m52.867542396s with status SUCCEEDED