[01:34:35.083] New invocation is queued and will start shortly
[01:34:36.295] Starting the invocation (attempt 1)
[01:34:36.314] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3a2a37a8ab276310e18b1a7383a6429900941c86"
[01:34:36.314] Popped gitiles commit info from properties and tags
[01:34:36.314] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:34:36.315] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:34:36.315] Buildbucket request:
{
"requestId": "8857807326681034304",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8857807326681034304",
"job": "chromium-m144/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7f1b1d288b083a4cd8660e06a4317e814eea6d39"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@7f1b1d288b083a4cd8660e06a4317e814eea6d39",
"title": "7f1b1d288b083a4cd8660e06a4317e814eea6d39",
"url": "https://chromium.googlesource.com/chromium/src/+/7f1b1d288b083a4cd8660e06a4317e814eea6d39"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "260d99e763882b5d7740252b3d4c05bf0f38c3b6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@260d99e763882b5d7740252b3d4c05bf0f38c3b6",
"title": "260d99e763882b5d7740252b3d4c05bf0f38c3b6",
"url": "https://chromium.googlesource.com/chromium/src/+/260d99e763882b5d7740252b3d4c05bf0f38c3b6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d7b80622cfab91c265741194e7942eefd2d21811"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@d7b80622cfab91c265741194e7942eefd2d21811",
"title": "d7b80622cfab91c265741194e7942eefd2d21811",
"url": "https://chromium.googlesource.com/chromium/src/+/d7b80622cfab91c265741194e7942eefd2d21811"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "80be69ef794ba862ff256b0b23f051cbbc32e1ed"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@80be69ef794ba862ff256b0b23f051cbbc32e1ed",
"title": "80be69ef794ba862ff256b0b23f051cbbc32e1ed",
"url": "https://chromium.googlesource.com/chromium/src/+/80be69ef794ba862ff256b0b23f051cbbc32e1ed"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2e9005764e56bf52be782b82b379a009e21007b7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@2e9005764e56bf52be782b82b379a009e21007b7",
"title": "2e9005764e56bf52be782b82b379a009e21007b7",
"url": "https://chromium.googlesource.com/chromium/src/+/2e9005764e56bf52be782b82b379a009e21007b7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "da10a6e39a68df912e9be9c12befd30ff5ecde5e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@da10a6e39a68df912e9be9c12befd30ff5ecde5e",
"title": "da10a6e39a68df912e9be9c12befd30ff5ecde5e",
"url": "https://chromium.googlesource.com/chromium/src/+/da10a6e39a68df912e9be9c12befd30ff5ecde5e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4d704cba739f0def4e3096d3890eb9c737494710"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@4d704cba739f0def4e3096d3890eb9c737494710",
"title": "4d704cba739f0def4e3096d3890eb9c737494710",
"url": "https://chromium.googlesource.com/chromium/src/+/4d704cba739f0def4e3096d3890eb9c737494710"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7db3b5e763257b08227e386a5fb79429537655b5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@7db3b5e763257b08227e386a5fb79429537655b5",
"title": "7db3b5e763257b08227e386a5fb79429537655b5",
"url": "https://chromium.googlesource.com/chromium/src/+/7db3b5e763257b08227e386a5fb79429537655b5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c9b734d8f730669e4f563856a561c2c3867691a7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@c9b734d8f730669e4f563856a561c2c3867691a7",
"title": "c9b734d8f730669e4f563856a561c2c3867691a7",
"url": "https://chromium.googlesource.com/chromium/src/+/c9b734d8f730669e4f563856a561c2c3867691a7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "612f6be010edf55941d58fc0901dbf6ca9ccbb2c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@612f6be010edf55941d58fc0901dbf6ca9ccbb2c",
"title": "612f6be010edf55941d58fc0901dbf6ca9ccbb2c",
"url": "https://chromium.googlesource.com/chromium/src/+/612f6be010edf55941d58fc0901dbf6ca9ccbb2c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3a2a37a8ab276310e18b1a7383a6429900941c86"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@3a2a37a8ab276310e18b1a7383a6429900941c86",
"title": "3a2a37a8ab276310e18b1a7383a6429900941c86",
"url": "https://chromium.googlesource.com/chromium/src/+/3a2a37a8ab276310e18b1a7383a6429900941c86"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3a2a37a8ab276310e18b1a7383a6429900941c86",
"ref": "refs/branch-heads/7559"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8857807326681034304"
},
{
"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"
}
}
[01:34:36.974] Scheduled build:
{
"id": "8692377264623224001",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win32-official"
},
"number": 294,
"createdBy": "project:chromium-m144",
"createTime": "2026-01-18T01:34:36.423175886Z",
"updateTime": "2026-01-18T01:34:36.423175886Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3a2a37a8ab276310e18b1a7383a6429900941c86",
"ref": "refs/branch-heads/7559"
}
}
}
[01:34:36.974] Task URL: https://cr-buildbucket.appspot.com/build/8692377264623224001
[01:34:36.974] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:2:0) after 5m33s
[01:34:37.984] Received PubSub notification, asking Buildbucket for the build status
[01:34:38.142] Build status: SCHEDULED
[01:38:09.957] Received PubSub notification, asking Buildbucket for the build status
[01:38:09.985] Build status: STARTED
[01:40:10.127] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:2:0)
[01:40:10.141] Build status: STARTED
[01:40:10.141] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:5:0) after 8m3s
[01:48:13.257] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:5:0)
[01:48:13.268] Build status: STARTED
[01:48:13.269] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:6:0) after 9m35s
[01:57:48.284] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:6:0)
[01:57:48.312] Build status: STARTED
[01:57:48.312] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:7:0) after 7m18s
[02:05:06.671] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:7:0)
[02:05:06.750] Build status: STARTED
[02:05:06.751] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:8:0) after 2m36s
[02:07:42.867] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:8:0)
[02:07:42.884] Build status: STARTED
[02:07:42.884] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:9:0) after 2m5s
[02:09:47.992] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:9:0)
[02:09:48.073] Build status: STARTED
[02:09:48.073] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:10:0) after 2m59s
[02:12:47.312] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:10:0)
[02:12:47.384] Build status: STARTED
[02:12:47.384] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:11:0) after 6m21s
[02:19:08.542] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:11:0)
[02:19:08.597] Build status: STARTED
[02:19:08.597] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:12:0) after 7m49s
[02:26:57.684] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:12:0)
[02:26:57.703] Build status: STARTED
[02:26:57.703] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:13:0) after 9m27s
[02:36:24.718] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:13:0)
[02:36:24.761] Build status: STARTED
[02:36:24.761] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:14:0) after 7m46s
[02:44:11.081] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:14:0)
[02:44:11.106] Build status: STARTED
[02:44:11.106] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:15:0) after 2m14s
[02:46:25.091] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:15:0)
[02:46:25.120] Build status: STARTED
[02:46:25.120] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:16:0) after 4m14s
[02:50:39.213] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:16:0)
[02:50:39.229] Build status: STARTED
[02:50:39.229] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:17:0) after 6m20s
[02:56:59.382] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:17:0)
[02:56:59.399] Build status: STARTED
[02:56:59.399] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:18:0) after 6m24s
[03:03:23.599] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:18:0)
[03:03:23.615] Build status: STARTED
[03:03:23.615] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:19:0) after 2m4s
[03:05:27.916] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:19:0)
[03:05:27.953] Build status: STARTED
[03:05:27.953] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:20:0) after 9m33s
[03:15:00.967] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:20:0)
[03:15:00.985] Build status: STARTED
[03:15:00.985] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:21:0) after 4m36s
[03:19:37.228] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:21:0)
[03:19:37.284] Build status: STARTED
[03:19:37.284] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:22:0) after 7m31s
[03:27:08.445] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:22:0)
[03:27:08.464] Build status: STARTED
[03:27:08.464] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:23:0) after 5m57s
[03:33:05.572] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:23:0)
[03:33:05.598] Build status: STARTED
[03:33:05.598] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:24:0) after 2m23s
[03:35:28.676] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:24:0)
[03:35:28.692] Build status: STARTED
[03:35:28.692] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:25:0) after 3m28s
[03:38:56.726] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:25:0)
[03:38:56.754] Build status: STARTED
[03:38:56.754] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:26:0) after 3m57s
[03:42:53.767] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:26:0)
[03:42:53.783] Build status: STARTED
[03:42:53.783] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:27:0) after 2m31s
[03:45:24.800] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:27:0)
[03:45:24.821] Build status: STARTED
[03:45:24.821] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:28:0) after 3m7s
[03:48:31.836] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:28:0)
[03:48:31.853] Build status: STARTED
[03:48:31.853] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:29:0) after 7m9s
[03:55:41.229] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:29:0)
[03:55:41.245] Build status: STARTED
[03:55:41.245] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:30:0) after 8m15s
[04:03:56.366] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:30:0)
[04:03:56.383] Build status: STARTED
[04:03:56.383] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:31:0) after 8m29s
[04:12:25.479] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:31:0)
[04:12:25.497] Build status: STARTED
[04:12:25.497] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:32:0) after 5m14s
[04:17:39.718] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:32:0)
[04:17:39.735] Build status: STARTED
[04:17:39.735] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:33:0) after 2m6s
[04:19:46.143] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:33:0)
[04:19:46.162] Build status: STARTED
[04:19:46.162] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:34:0) after 2m14s
[04:22:00.150] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:34:0)
[04:22:00.168] Build status: STARTED
[04:22:00.168] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:35:0) after 6m14s
[04:28:14.256] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:35:0)
[04:28:14.308] Build status: STARTED
[04:28:14.308] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:36:0) after 4m43s
[04:32:57.463] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:36:0)
[04:32:57.483] Build status: STARTED
[04:32:57.483] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:37:0) after 9m11s
[04:42:08.630] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:37:0)
[04:42:08.647] Build status: STARTED
[04:42:08.647] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:38:0) after 8m8s
[04:50:16.764] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:38:0)
[04:50:16.780] Build status: STARTED
[04:50:16.780] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:39:0) after 8m25s
[04:58:41.792] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:39:0)
[04:58:41.807] Build status: STARTED
[04:58:41.807] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:40:0) after 8m41s
[05:07:22.849] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:40:0)
[05:07:22.863] Build status: STARTED
[05:07:22.863] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:41:0) after 5m17s
[05:12:39.894] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:41:0)
[05:12:39.920] Build status: STARTED
[05:12:39.920] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:42:0) after 1m19s
[05:13:58.945] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:42:0)
[05:13:58.962] Build status: STARTED
[05:13:58.963] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:43:0) after 7m43s
[05:21:41.978] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:43:0)
[05:21:42.048] Build status: STARTED
[05:21:42.048] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:44:0) after 6m35s
[05:28:17.095] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:44:0)
[05:28:17.116] Build status: STARTED
[05:28:17.117] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:45:0) after 9m33s
[05:37:50.197] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:45:0)
[05:37:50.214] Build status: STARTED
[05:37:50.214] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:46:0) after 6m7s
[05:43:57.228] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:46:0)
[05:43:57.242] Build status: STARTED
[05:43:57.242] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8857807326681034304:47:0) after 9m43s
[05:50:55.908] Received PubSub notification, asking Buildbucket for the build status
[05:50:55.927] Build:
{
"id": "8692377264623224001",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win32-official"
},
"number": 294,
"createdBy": "project:chromium-m144",
"createTime": "2026-01-18T01:34:36.423175886Z",
"startTime": "2026-01-18T01:38:09.682495557Z",
"endTime": "2026-01-18T05:50:55.556739607Z",
"updateTime": "2026-01-18T05:50:55.556739607Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3a2a37a8ab276310e18b1a7383a6429900941c86",
"ref": "refs/branch-heads/7559"
}
}
}
[05:50:55.927] Invocation finished in 4h16m20.849764044s with status SUCCEEDED