[02:17:38.504] New invocation is queued and will start shortly
[02:17:39.612] Starting the invocation (attempt 1)
[02:17:39.645] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/353578fdf5c3f2a7e8eff08fd81698ccd851bb30"
[02:17:39.645] Popped gitiles commit info from properties and tags
[02:17:39.645] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:17:39.645] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:17:39.645] Buildbucket request:
{
"requestId": "8850647457423064048",
"builder": {
"project": "chromium-m147",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8850647457423064048",
"job": "chromium-m147/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3f5991eff238b1b3c6da60faf655bb02951882b1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@3f5991eff238b1b3c6da60faf655bb02951882b1",
"title": "3f5991eff238b1b3c6da60faf655bb02951882b1",
"url": "https://chromium.googlesource.com/chromium/src/+/3f5991eff238b1b3c6da60faf655bb02951882b1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "36fa941d6cca47a4e615d698b1af095e37f8d47c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@36fa941d6cca47a4e615d698b1af095e37f8d47c",
"title": "36fa941d6cca47a4e615d698b1af095e37f8d47c",
"url": "https://chromium.googlesource.com/chromium/src/+/36fa941d6cca47a4e615d698b1af095e37f8d47c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "46f302303eb4032c3e1591861b3c6043c13c55e1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@46f302303eb4032c3e1591861b3c6043c13c55e1",
"title": "46f302303eb4032c3e1591861b3c6043c13c55e1",
"url": "https://chromium.googlesource.com/chromium/src/+/46f302303eb4032c3e1591861b3c6043c13c55e1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7887b2ef2fa4f975f09a0a4903acc8e6e59c377e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@7887b2ef2fa4f975f09a0a4903acc8e6e59c377e",
"title": "7887b2ef2fa4f975f09a0a4903acc8e6e59c377e",
"url": "https://chromium.googlesource.com/chromium/src/+/7887b2ef2fa4f975f09a0a4903acc8e6e59c377e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ecffe86e063fa41aa811224f3ad07b36953a6106"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@ecffe86e063fa41aa811224f3ad07b36953a6106",
"title": "ecffe86e063fa41aa811224f3ad07b36953a6106",
"url": "https://chromium.googlesource.com/chromium/src/+/ecffe86e063fa41aa811224f3ad07b36953a6106"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c986af0ee44648443d6d34699259f1af3cac66de"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@c986af0ee44648443d6d34699259f1af3cac66de",
"title": "c986af0ee44648443d6d34699259f1af3cac66de",
"url": "https://chromium.googlesource.com/chromium/src/+/c986af0ee44648443d6d34699259f1af3cac66de"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "15a60304fe52da8395f94ec039eec85c7bfb536d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@15a60304fe52da8395f94ec039eec85c7bfb536d",
"title": "15a60304fe52da8395f94ec039eec85c7bfb536d",
"url": "https://chromium.googlesource.com/chromium/src/+/15a60304fe52da8395f94ec039eec85c7bfb536d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0e0db7ac74ef06ca4578076c0b8bef1ce1ee0247"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@0e0db7ac74ef06ca4578076c0b8bef1ce1ee0247",
"title": "0e0db7ac74ef06ca4578076c0b8bef1ce1ee0247",
"url": "https://chromium.googlesource.com/chromium/src/+/0e0db7ac74ef06ca4578076c0b8bef1ce1ee0247"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "30285456574ebdcb9f9ecc8bdac9e0d4b85955f0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@30285456574ebdcb9f9ecc8bdac9e0d4b85955f0",
"title": "30285456574ebdcb9f9ecc8bdac9e0d4b85955f0",
"url": "https://chromium.googlesource.com/chromium/src/+/30285456574ebdcb9f9ecc8bdac9e0d4b85955f0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e34166e05c13a4f929be110376f7bb89a2a275eb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@e34166e05c13a4f929be110376f7bb89a2a275eb",
"title": "e34166e05c13a4f929be110376f7bb89a2a275eb",
"url": "https://chromium.googlesource.com/chromium/src/+/e34166e05c13a4f929be110376f7bb89a2a275eb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "353578fdf5c3f2a7e8eff08fd81698ccd851bb30"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@353578fdf5c3f2a7e8eff08fd81698ccd851bb30",
"title": "353578fdf5c3f2a7e8eff08fd81698ccd851bb30",
"url": "https://chromium.googlesource.com/chromium/src/+/353578fdf5c3f2a7e8eff08fd81698ccd851bb30"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "353578fdf5c3f2a7e8eff08fd81698ccd851bb30",
"ref": "refs/branch-heads/7727"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8850647457423064048"
},
{
"key": "scheduler_job_id",
"value": "chromium-m147/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:17:43.084] Scheduled build:
{
"id": "8685217395510350865",
"builder": {
"project": "chromium-m147",
"bucket": "ci",
"builder": "win-official"
},
"number": 204,
"createdBy": "project:chromium-m147",
"createTime": "2026-04-07T02:17:39.704804327Z",
"updateTime": "2026-04-07T02:17:39.704804327Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "353578fdf5c3f2a7e8eff08fd81698ccd851bb30",
"ref": "refs/branch-heads/7727"
}
}
}
[02:17:43.084] Task URL: https://cr-buildbucket.appspot.com/build/8685217395510350865
[02:17:43.084] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:2:0) after 4m54s
[02:17:43.472] Received PubSub notification, asking Buildbucket for the build status
[02:17:43.485] Build status: SCHEDULED
[02:17:47.744] Received PubSub notification, asking Buildbucket for the build status
[02:17:47.768] Build status: STARTED
[02:22:37.191] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:2:0)
[02:22:37.218] Build status: STARTED
[02:22:37.218] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:5:0) after 1m22s
[02:23:59.366] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:5:0)
[02:23:59.451] Build status: STARTED
[02:23:59.452] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:6:0) after 9m0s
[02:32:59.577] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:6:0)
[02:32:59.607] Build status: STARTED
[02:32:59.607] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:7:0) after 3m6s
[02:36:05.624] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:7:0)
[02:36:05.678] Build status: STARTED
[02:36:05.678] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:8:0) after 3m57s
[02:40:02.783] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:8:0)
[02:40:02.800] Build status: STARTED
[02:40:02.800] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:9:0) after 2m59s
[02:43:01.865] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:9:0)
[02:43:01.897] Build status: STARTED
[02:43:01.897] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:10:0) after 8m27s
[02:51:28.976] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:10:0)
[02:51:29.007] Build status: STARTED
[02:51:29.007] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:11:0) after 4m11s
[02:55:40.057] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:11:0)
[02:55:40.079] Build status: STARTED
[02:55:40.079] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:12:0) after 7m40s
[03:03:20.095] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:12:0)
[03:03:20.117] Build status: STARTED
[03:03:20.117] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:13:0) after 5m51s
[03:09:11.193] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:13:0)
[03:09:11.213] Build status: STARTED
[03:09:11.213] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:14:0) after 6m33s
[03:15:44.280] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:14:0)
[03:15:44.352] Build status: STARTED
[03:15:44.352] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:15:0) after 6m47s
[03:22:31.396] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:15:0)
[03:22:31.443] Build status: STARTED
[03:22:31.443] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:16:0) after 8m42s
[03:31:13.458] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:16:0)
[03:31:13.474] Build status: STARTED
[03:31:13.474] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:17:0) after 2m20s
[03:33:33.554] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:17:0)
[03:33:33.646] Build status: STARTED
[03:33:33.646] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:18:0) after 7m18s
[03:40:51.663] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:18:0)
[03:40:51.681] Build status: STARTED
[03:40:51.681] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:19:0) after 6m54s
[03:47:45.698] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:19:0)
[03:47:45.715] Build status: STARTED
[03:47:45.715] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:20:0) after 5m12s
[03:52:57.733] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:20:0)
[03:52:57.752] Build status: STARTED
[03:52:57.752] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:21:0) after 8m31s
[04:01:28.816] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:21:0)
[04:01:28.831] Build status: STARTED
[04:01:28.831] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:22:0) after 8m27s
[04:09:55.848] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:22:0)
[04:09:55.865] Build status: STARTED
[04:09:55.865] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:23:0) after 8m59s
[04:18:54.889] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:23:0)
[04:18:54.905] Build status: STARTED
[04:18:54.905] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:24:0) after 8m41s
[04:27:35.921] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:24:0)
[04:27:35.939] Build status: STARTED
[04:27:35.939] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:25:0) after 1m43s
[04:29:18.955] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:25:0)
[04:29:18.971] Build status: STARTED
[04:29:18.971] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:26:0) after 7m37s
[04:36:56.082] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:26:0)
[04:36:56.108] Build status: STARTED
[04:36:56.108] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:27:0) after 7m21s
[04:44:17.210] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:27:0)
[04:44:17.236] Build status: STARTED
[04:44:17.236] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:28:0) after 9m56s
[04:54:13.253] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:28:0)
[04:54:13.393] Build status: STARTED
[04:54:13.393] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:29:0) after 2m20s
[04:56:33.407] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:29:0)
[04:56:33.452] Build status: STARTED
[04:56:33.452] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:30:0) after 3m23s
[04:59:56.502] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:30:0)
[04:59:56.548] Build status: STARTED
[04:59:56.548] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:31:0) after 1m53s
[05:01:49.568] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:31:0)
[05:01:49.589] Build status: STARTED
[05:01:49.589] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:32:0) after 5m5s
[05:06:54.605] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:32:0)
[05:06:54.640] Build status: STARTED
[05:06:54.640] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:33:0) after 4m21s
[05:11:15.655] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:33:0)
[05:11:15.671] Build status: STARTED
[05:11:15.672] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:34:0) after 5m41s
[05:16:56.689] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:34:0)
[05:16:56.723] Build status: STARTED
[05:16:56.723] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:35:0) after 7m33s
[05:24:29.748] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:35:0)
[05:24:29.768] Build status: STARTED
[05:24:29.768] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:36:0) after 6m44s
[05:31:13.901] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:36:0)
[05:31:13.920] Build status: STARTED
[05:31:13.921] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:37:0) after 9m50s
[05:41:03.939] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:37:0)
[05:41:03.966] Build status: STARTED
[05:41:03.966] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:38:0) after 6m2s
[05:47:06.062] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8850647457423064048:38:0)
[05:47:06.077] Build:
{
"id": "8685217395510350865",
"builder": {
"project": "chromium-m147",
"bucket": "ci",
"builder": "win-official"
},
"number": 204,
"createdBy": "project:chromium-m147",
"createTime": "2026-04-07T02:17:39.704804327Z",
"startTime": "2026-04-07T02:17:46.950037281Z",
"endTime": "2026-04-07T05:46:42.883512657Z",
"updateTime": "2026-04-07T05:46:42.883512657Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "353578fdf5c3f2a7e8eff08fd81698ccd851bb30",
"ref": "refs/branch-heads/7727"
}
}
}
[05:47:06.077] Invocation finished in 3h29m27.579930516s with status SUCCEEDED