[19:25:23.508] New invocation is queued and will start shortly
[19:25:25.598] Starting the invocation (attempt 1)
[19:25:25.631] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/83ed7514280116d68c7584283c0d34260a1d17e9"
[19:25:25.631] Popped gitiles commit info from properties and tags
[19:25:25.631] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:25:25.631] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:25:25.632] Buildbucket request:
{
"requestId": "8843063248766357280",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8843063248766357280",
"job": "chromium-m150/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "73adf550a6d7d075e919669675826078c4c0d405"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@73adf550a6d7d075e919669675826078c4c0d405",
"title": "73adf550a6d7d075e919669675826078c4c0d405",
"url": "https://chromium.googlesource.com/chromium/src/+/73adf550a6d7d075e919669675826078c4c0d405"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0f5aa8b97e76582ea45412c9915fae879e4f177c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@0f5aa8b97e76582ea45412c9915fae879e4f177c",
"title": "0f5aa8b97e76582ea45412c9915fae879e4f177c",
"url": "https://chromium.googlesource.com/chromium/src/+/0f5aa8b97e76582ea45412c9915fae879e4f177c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7cb3469a819bc4a82c1e5623be0fbbae1b216da0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@7cb3469a819bc4a82c1e5623be0fbbae1b216da0",
"title": "7cb3469a819bc4a82c1e5623be0fbbae1b216da0",
"url": "https://chromium.googlesource.com/chromium/src/+/7cb3469a819bc4a82c1e5623be0fbbae1b216da0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a33370ca985725640f4192e22b9303026c6777e3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@a33370ca985725640f4192e22b9303026c6777e3",
"title": "a33370ca985725640f4192e22b9303026c6777e3",
"url": "https://chromium.googlesource.com/chromium/src/+/a33370ca985725640f4192e22b9303026c6777e3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "90951bc14ab8e0ba71ba7f57a7ce5841c94f7e05"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@90951bc14ab8e0ba71ba7f57a7ce5841c94f7e05",
"title": "90951bc14ab8e0ba71ba7f57a7ce5841c94f7e05",
"url": "https://chromium.googlesource.com/chromium/src/+/90951bc14ab8e0ba71ba7f57a7ce5841c94f7e05"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7fec1606c3ae38328980dffc7cb7b4644bfb5e88"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@7fec1606c3ae38328980dffc7cb7b4644bfb5e88",
"title": "7fec1606c3ae38328980dffc7cb7b4644bfb5e88",
"url": "https://chromium.googlesource.com/chromium/src/+/7fec1606c3ae38328980dffc7cb7b4644bfb5e88"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3037cbdc733379dafda6396095d074d6b7ea2171"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@3037cbdc733379dafda6396095d074d6b7ea2171",
"title": "3037cbdc733379dafda6396095d074d6b7ea2171",
"url": "https://chromium.googlesource.com/chromium/src/+/3037cbdc733379dafda6396095d074d6b7ea2171"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6efa11782be691217c8aa5d303310051363fd3ee"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@6efa11782be691217c8aa5d303310051363fd3ee",
"title": "6efa11782be691217c8aa5d303310051363fd3ee",
"url": "https://chromium.googlesource.com/chromium/src/+/6efa11782be691217c8aa5d303310051363fd3ee"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "83ed7514280116d68c7584283c0d34260a1d17e9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@83ed7514280116d68c7584283c0d34260a1d17e9",
"title": "83ed7514280116d68c7584283c0d34260a1d17e9",
"url": "https://chromium.googlesource.com/chromium/src/+/83ed7514280116d68c7584283c0d34260a1d17e9"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "83ed7514280116d68c7584283c0d34260a1d17e9",
"ref": "refs/branch-heads/7871"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8843063248766357280"
},
{
"key": "scheduler_job_id",
"value": "chromium-m150/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:25:26.291] Scheduled build:
{
"id": "8677633185805805073",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "win-official"
},
"number": 198,
"createdBy": "project:chromium-m150",
"createTime": "2026-06-29T19:25:25.710602328Z",
"updateTime": "2026-06-29T19:25:25.710602328Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "83ed7514280116d68c7584283c0d34260a1d17e9",
"ref": "refs/branch-heads/7871"
}
}
}
[19:25:26.292] Task URL: https://cr-buildbucket.appspot.com/build/8677633185805805073
[19:25:26.292] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:2:0) after 1m34s
[19:25:27.308] Received PubSub notification, asking Buildbucket for the build status
[19:25:27.333] Build status: SCHEDULED
[19:27:00.319] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:2:0)
[19:27:00.393] Build status: SCHEDULED
[19:27:00.393] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:4:0) after 5m43s
[19:27:41.899] Received PubSub notification, asking Buildbucket for the build status
[19:27:41.926] Build status: STARTED
[19:32:43.455] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:4:0)
[19:32:43.482] Build status: STARTED
[19:32:43.482] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:6:0) after 2m56s
[19:35:39.498] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:6:0)
[19:35:39.514] Build status: STARTED
[19:35:39.514] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:7:0) after 6m15s
[19:41:54.578] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:7:0)
[19:41:54.610] Build status: STARTED
[19:41:54.610] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:8:0) after 4m3s
[19:45:57.637] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:8:0)
[19:45:57.689] Build status: STARTED
[19:45:57.689] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:9:0) after 9m50s
[19:55:47.742] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:9:0)
[19:55:47.765] Build status: STARTED
[19:55:47.766] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:10:0) after 6m27s
[20:02:14.785] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:10:0)
[20:02:14.804] Build status: STARTED
[20:02:14.804] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:11:0) after 1m4s
[20:03:18.824] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:11:0)
[20:03:18.844] Build status: STARTED
[20:03:18.844] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:12:0) after 9m31s
[20:12:49.886] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:12:0)
[20:12:49.901] Build status: STARTED
[20:12:49.901] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:13:0) after 8m40s
[20:21:29.918] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:13:0)
[20:21:29.934] Build status: STARTED
[20:21:29.934] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:14:0) after 2m8s
[20:23:38.062] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:14:0)
[20:23:38.083] Build status: STARTED
[20:23:38.083] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:15:0) after 2m52s
[20:26:30.133] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:15:0)
[20:26:30.153] Build status: STARTED
[20:26:30.153] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:16:0) after 1m31s
[20:28:01.221] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:16:0)
[20:28:01.268] Build status: STARTED
[20:28:01.268] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:17:0) after 5m48s
[20:33:49.283] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:17:0)
[20:33:49.305] Build status: STARTED
[20:33:49.305] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:18:0) after 1m28s
[20:35:17.280] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:18:0)
[20:35:17.303] Build status: STARTED
[20:35:17.303] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:19:0) after 4m25s
[20:39:42.386] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:19:0)
[20:39:42.407] Build status: STARTED
[20:39:42.407] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:20:0) after 9m43s
[20:49:25.521] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:20:0)
[20:49:25.542] Build status: STARTED
[20:49:25.542] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:21:0) after 8m45s
[20:58:10.579] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:21:0)
[20:58:10.604] Build status: STARTED
[20:58:10.604] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:22:0) after 1m29s
[20:59:39.644] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:22:0)
[20:59:39.660] Build status: STARTED
[20:59:39.660] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:23:0) after 6m29s
[21:06:08.707] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:23:0)
[21:06:08.721] Build status: STARTED
[21:06:08.721] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:24:0) after 1m28s
[21:07:36.692] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:24:0)
[21:07:36.714] Build status: STARTED
[21:07:36.714] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:25:0) after 8m17s
[21:15:53.733] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:25:0)
[21:15:53.750] Build status: STARTED
[21:15:53.750] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:26:0) after 4m51s
[21:20:44.865] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:26:0)
[21:20:44.884] Build status: STARTED
[21:20:44.884] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:27:0) after 5m44s
[21:26:28.901] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:27:0)
[21:26:28.918] Build status: STARTED
[21:26:28.918] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:28:0) after 8m39s
[21:35:07.922] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:28:0)
[21:35:07.940] Build status: STARTED
[21:35:07.940] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:29:0) after 4m30s
[21:39:37.958] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:29:0)
[21:39:37.986] Build status: STARTED
[21:39:37.986] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:30:0) after 7m58s
[21:47:36.054] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:30:0)
[21:47:36.078] Build status: STARTED
[21:47:36.078] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:31:0) after 9m44s
[21:57:20.093] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:31:0)
[21:57:20.127] Build status: STARTED
[21:57:20.127] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:32:0) after 8m17s
[22:05:37.203] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:32:0)
[22:05:37.240] Build status: STARTED
[22:05:37.240] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:33:0) after 2m12s
[22:07:49.255] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:33:0)
[22:07:49.273] Build status: STARTED
[22:07:49.273] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:34:0) after 4m35s
[22:12:24.387] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:34:0)
[22:12:24.476] Build status: STARTED
[22:12:24.476] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:35:0) after 8m48s
[22:21:12.626] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:35:0)
[22:21:12.650] Build status: STARTED
[22:21:12.651] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843063248766357280:36:0) after 8m21s
[22:26:21.491] Received PubSub notification, asking Buildbucket for the build status
[22:26:21.577] Build:
{
"id": "8677633185805805073",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "win-official"
},
"number": 198,
"createdBy": "project:chromium-m150",
"createTime": "2026-06-29T19:25:25.710602328Z",
"startTime": "2026-06-29T19:27:41.685626051Z",
"endTime": "2026-06-29T22:26:21.133206875Z",
"updateTime": "2026-06-29T22:26:21.133206875Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "83ed7514280116d68c7584283c0d34260a1d17e9",
"ref": "refs/branch-heads/7871"
}
}
}
[22:26:21.577] Invocation finished in 3h0m58.073111618s with status SUCCEEDED