[04:30:02.112] New invocation is queued and will start shortly
[04:30:03.190] Starting the invocation (attempt 1)
[04:30:03.281] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4536861c46e946447582d841d1e1c953c644278d"
[04:30:03.281] Popped gitiles commit info from properties and tags
[04:30:03.281] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:30:03.281] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:30:03.281] Buildbucket request:
{
"requestId": "8855259573236802096",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8855259573236802096",
"job": "chromium-m146/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6936651f1bf0ad416bcc675dc4030cee5414b14c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@6936651f1bf0ad416bcc675dc4030cee5414b14c",
"title": "6936651f1bf0ad416bcc675dc4030cee5414b14c",
"url": "https://chromium.googlesource.com/chromium/src/+/6936651f1bf0ad416bcc675dc4030cee5414b14c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a52a1bb044329b49695e9fa2ad54c5692a662faf"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@a52a1bb044329b49695e9fa2ad54c5692a662faf",
"title": "a52a1bb044329b49695e9fa2ad54c5692a662faf",
"url": "https://chromium.googlesource.com/chromium/src/+/a52a1bb044329b49695e9fa2ad54c5692a662faf"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2e2c8e933fd0294810ed0cec862713128e8f6352"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@2e2c8e933fd0294810ed0cec862713128e8f6352",
"title": "2e2c8e933fd0294810ed0cec862713128e8f6352",
"url": "https://chromium.googlesource.com/chromium/src/+/2e2c8e933fd0294810ed0cec862713128e8f6352"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "49b2597583d4ff07d0ebfd48cc14f17ccc6a3cad"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@49b2597583d4ff07d0ebfd48cc14f17ccc6a3cad",
"title": "49b2597583d4ff07d0ebfd48cc14f17ccc6a3cad",
"url": "https://chromium.googlesource.com/chromium/src/+/49b2597583d4ff07d0ebfd48cc14f17ccc6a3cad"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "da9881af80812f0cc9891d49a75d69324632faab"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@da9881af80812f0cc9891d49a75d69324632faab",
"title": "da9881af80812f0cc9891d49a75d69324632faab",
"url": "https://chromium.googlesource.com/chromium/src/+/da9881af80812f0cc9891d49a75d69324632faab"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "37b64c661dc82f15b604e152a379e97bf7a464de"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@37b64c661dc82f15b604e152a379e97bf7a464de",
"title": "37b64c661dc82f15b604e152a379e97bf7a464de",
"url": "https://chromium.googlesource.com/chromium/src/+/37b64c661dc82f15b604e152a379e97bf7a464de"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "04ff7be8a3272d0070e96297fef0c41ca95ab7a1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@04ff7be8a3272d0070e96297fef0c41ca95ab7a1",
"title": "04ff7be8a3272d0070e96297fef0c41ca95ab7a1",
"url": "https://chromium.googlesource.com/chromium/src/+/04ff7be8a3272d0070e96297fef0c41ca95ab7a1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4536861c46e946447582d841d1e1c953c644278d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@4536861c46e946447582d841d1e1c953c644278d",
"title": "4536861c46e946447582d841d1e1c953c644278d",
"url": "https://chromium.googlesource.com/chromium/src/+/4536861c46e946447582d841d1e1c953c644278d"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4536861c46e946447582d841d1e1c953c644278d",
"ref": "refs/branch-heads/7680"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8855259573236802096"
},
{
"key": "scheduler_job_id",
"value": "chromium-m146/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:30:03.961] Scheduled build:
{
"id": "8689829511256080369",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "win32-official"
},
"number": 27,
"createdBy": "project:chromium-m146",
"createTime": "2026-02-15T04:30:03.374437413Z",
"updateTime": "2026-02-15T04:30:03.374437413Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4536861c46e946447582d841d1e1c953c644278d",
"ref": "refs/branch-heads/7680"
}
}
}
[04:30:03.961] Task URL: https://cr-buildbucket.appspot.com/build/8689829511256080369
[04:30:03.961] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:2:0) after 7m4s
[04:30:04.019] Received PubSub notification, asking Buildbucket for the build status
[04:30:04.071] Build status: SCHEDULED
[04:30:09.030] Received PubSub notification, asking Buildbucket for the build status
[04:30:09.046] Build status: STARTED
[04:37:08.202] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:2:0)
[04:37:08.220] Build status: STARTED
[04:37:08.220] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:5:0) after 3m15s
[04:40:23.264] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:5:0)
[04:40:23.291] Build status: STARTED
[04:40:23.291] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:6:0) after 7m48s
[04:48:11.306] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:6:0)
[04:48:11.328] Build status: STARTED
[04:48:11.328] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:7:0) after 3m26s
[04:51:37.559] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:7:0)
[04:51:37.628] Build status: STARTED
[04:51:37.628] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:8:0) after 6m2s
[04:57:39.783] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:8:0)
[04:57:39.798] Build status: STARTED
[04:57:39.798] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:9:0) after 7m48s
[05:05:27.814] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:9:0)
[05:05:27.866] Build status: STARTED
[05:05:27.866] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:10:0) after 7m45s
[05:13:12.956] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:10:0)
[05:13:12.974] Build status: STARTED
[05:13:12.974] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:11:0) after 2m56s
[05:16:08.960] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:11:0)
[05:16:09.013] Build status: STARTED
[05:16:09.013] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:12:0) after 1m11s
[05:17:20.044] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:12:0)
[05:17:20.067] Build status: STARTED
[05:17:20.067] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:13:0) after 6m18s
[05:23:38.080] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:13:0)
[05:23:38.095] Build status: STARTED
[05:23:38.095] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:14:0) after 4m18s
[05:27:56.126] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:14:0)
[05:27:56.224] Build status: STARTED
[05:27:56.224] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:15:0) after 9m18s
[05:37:14.286] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:15:0)
[05:37:14.311] Build status: STARTED
[05:37:14.311] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:16:0) after 9m20s
[05:46:34.317] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:16:0)
[05:46:34.335] Build status: STARTED
[05:46:34.336] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:17:0) after 3m27s
[05:50:01.350] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:17:0)
[05:50:01.383] Build status: STARTED
[05:50:01.383] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:18:0) after 3m34s
[05:53:35.396] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:18:0)
[05:53:35.421] Build status: STARTED
[05:53:35.421] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:19:0) after 3m15s
[05:56:50.441] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:19:0)
[05:56:50.459] Build status: STARTED
[05:56:50.459] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:20:0) after 9m6s
[06:05:56.577] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:20:0)
[06:05:56.610] Build status: STARTED
[06:05:56.610] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:21:0) after 5m17s
[06:11:13.628] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:21:0)
[06:11:13.643] Build status: STARTED
[06:11:13.643] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:22:0) after 5m19s
[06:16:32.683] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:22:0)
[06:16:32.702] Build status: STARTED
[06:16:32.702] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:23:0) after 3m15s
[06:19:47.797] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:23:0)
[06:19:47.812] Build status: STARTED
[06:19:47.812] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:24:0) after 8m54s
[06:28:41.927] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:24:0)
[06:28:41.954] Build status: STARTED
[06:28:41.954] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:25:0) after 6m28s
[06:35:09.971] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:25:0)
[06:35:09.988] Build status: STARTED
[06:35:09.988] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:26:0) after 3m10s
[06:38:19.999] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:26:0)
[06:38:20.016] Build status: STARTED
[06:38:20.016] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:27:0) after 9m40s
[06:48:00.073] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:27:0)
[06:48:00.089] Build status: STARTED
[06:48:00.089] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:28:0) after 9m43s
[06:57:43.105] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:28:0)
[06:57:43.127] Build status: STARTED
[06:57:43.127] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:29:0) after 6m7s
[07:03:50.145] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:29:0)
[07:03:50.266] Build status: STARTED
[07:03:50.266] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:30:0) after 4m2s
[07:07:52.237] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:30:0)
[07:07:52.252] Build status: STARTED
[07:07:52.252] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:31:0) after 5m50s
[07:13:42.268] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:31:0)
[07:13:42.284] Build status: STARTED
[07:13:42.285] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:32:0) after 3m43s
[07:17:25.510] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:32:0)
[07:17:25.532] Build status: STARTED
[07:17:25.532] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:33:0) after 2m54s
[07:20:19.667] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:33:0)
[07:20:19.896] Build status: STARTED
[07:20:19.896] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:34:0) after 5m14s
[07:25:33.915] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:34:0)
[07:25:33.933] Build status: STARTED
[07:25:33.933] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:35:0) after 1m6s
[07:26:39.904] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:35:0)
[07:26:39.922] Build status: STARTED
[07:26:39.922] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:36:0) after 9m11s
[07:35:50.942] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:36:0)
[07:35:50.962] Build status: STARTED
[07:35:50.962] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:37:0) after 5m10s
[07:41:01.087] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:37:0)
[07:41:01.141] Build status: STARTED
[07:41:01.141] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:38:0) after 3m41s
[07:44:42.357] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:38:0)
[07:44:42.378] Build status: STARTED
[07:44:42.378] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:39:0) after 7m14s
[07:51:56.390] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:39:0)
[07:51:56.428] Build status: STARTED
[07:51:56.428] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:40:0) after 3m24s
[07:55:20.446] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:40:0)
[07:55:20.528] Build status: STARTED
[07:55:20.528] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:41:0) after 4m34s
[07:59:54.656] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:41:0)
[07:59:54.717] Build status: STARTED
[07:59:54.717] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:42:0) after 4m57s
[08:04:52.068] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:42:0)
[08:04:52.081] Build status: STARTED
[08:04:52.081] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:43:0) after 3m31s
[08:08:23.443] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:43:0)
[08:08:23.528] Build status: STARTED
[08:08:23.528] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:44:0) after 1m7s
[08:09:30.981] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:44:0)
[08:09:31.022] Build status: STARTED
[08:09:31.022] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:45:0) after 1m31s
[08:11:02.055] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:45:0)
[08:11:02.075] Build status: STARTED
[08:11:02.075] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:46:0) after 4m40s
[08:15:42.097] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:46:0)
[08:15:42.114] Build status: STARTED
[08:15:42.114] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8855259573236802096:47:0) after 8m54s
[08:24:02.444] Received PubSub notification, asking Buildbucket for the build status
[08:24:02.504] Build:
{
"id": "8689829511256080369",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "win32-official"
},
"number": 27,
"createdBy": "project:chromium-m146",
"createTime": "2026-02-15T04:30:03.374437413Z",
"startTime": "2026-02-15T04:30:08.861385730Z",
"endTime": "2026-02-15T08:24:00.469725946Z",
"updateTime": "2026-02-15T08:24:00.469725946Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4536861c46e946447582d841d1e1c953c644278d",
"ref": "refs/branch-heads/7680"
}
}
}
[08:24:02.504] Invocation finished in 3h54m0.40253383s with status SUCCEEDED