[00:02:16.666] New invocation is queued and will start shortly
[00:02:17.714] Starting the invocation (attempt 1)
[00:02:17.736] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/33411a86e0225283b773df0bfc47f562ef6661ba"
[00:02:17.736] Popped gitiles commit info from properties and tags
[00:02:17.736] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:02:17.736] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:02:17.737] Buildbucket request:
{
"requestId": "8854279852440149488",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "linux-cast-x64-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8854279852440149488",
"job": "chromium-m146/linux-cast-x64-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9b2b5f2f7e6517f42f1f33d494f32958ba87d314"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@9b2b5f2f7e6517f42f1f33d494f32958ba87d314",
"title": "9b2b5f2f7e6517f42f1f33d494f32958ba87d314",
"url": "https://chromium.googlesource.com/chromium/src/+/9b2b5f2f7e6517f42f1f33d494f32958ba87d314"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0c8ab6fa9ebc487b61b798fa647308b3bc4ed58a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@0c8ab6fa9ebc487b61b798fa647308b3bc4ed58a",
"title": "0c8ab6fa9ebc487b61b798fa647308b3bc4ed58a",
"url": "https://chromium.googlesource.com/chromium/src/+/0c8ab6fa9ebc487b61b798fa647308b3bc4ed58a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "00a0b858928c52b229b73a0c514d19d378b74226"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@00a0b858928c52b229b73a0c514d19d378b74226",
"title": "00a0b858928c52b229b73a0c514d19d378b74226",
"url": "https://chromium.googlesource.com/chromium/src/+/00a0b858928c52b229b73a0c514d19d378b74226"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f0fe9043ad0c687f864539672c5398d03d57cd62"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@f0fe9043ad0c687f864539672c5398d03d57cd62",
"title": "f0fe9043ad0c687f864539672c5398d03d57cd62",
"url": "https://chromium.googlesource.com/chromium/src/+/f0fe9043ad0c687f864539672c5398d03d57cd62"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cc164610e0788c003a734a474ef3980ebc99fbf8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@cc164610e0788c003a734a474ef3980ebc99fbf8",
"title": "cc164610e0788c003a734a474ef3980ebc99fbf8",
"url": "https://chromium.googlesource.com/chromium/src/+/cc164610e0788c003a734a474ef3980ebc99fbf8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3f5932df33b98aa3252dceb9cd68b55d4eb720c9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@3f5932df33b98aa3252dceb9cd68b55d4eb720c9",
"title": "3f5932df33b98aa3252dceb9cd68b55d4eb720c9",
"url": "https://chromium.googlesource.com/chromium/src/+/3f5932df33b98aa3252dceb9cd68b55d4eb720c9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3039fff5519f76d50b540846ba35d6689722030c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@3039fff5519f76d50b540846ba35d6689722030c",
"title": "3039fff5519f76d50b540846ba35d6689722030c",
"url": "https://chromium.googlesource.com/chromium/src/+/3039fff5519f76d50b540846ba35d6689722030c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "24eeed45960e647eaf40cb0efe3378772d98d3c2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@24eeed45960e647eaf40cb0efe3378772d98d3c2",
"title": "24eeed45960e647eaf40cb0efe3378772d98d3c2",
"url": "https://chromium.googlesource.com/chromium/src/+/24eeed45960e647eaf40cb0efe3378772d98d3c2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d989e81fccfb1e5ff7e830512592b8ad3b40e171"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@d989e81fccfb1e5ff7e830512592b8ad3b40e171",
"title": "d989e81fccfb1e5ff7e830512592b8ad3b40e171",
"url": "https://chromium.googlesource.com/chromium/src/+/d989e81fccfb1e5ff7e830512592b8ad3b40e171"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "12852d743220cb1e6f6da5521214ca9d8fcba268"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@12852d743220cb1e6f6da5521214ca9d8fcba268",
"title": "12852d743220cb1e6f6da5521214ca9d8fcba268",
"url": "https://chromium.googlesource.com/chromium/src/+/12852d743220cb1e6f6da5521214ca9d8fcba268"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "95b1b715d8351bd09dc1c4af98b3dd74368db366"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@95b1b715d8351bd09dc1c4af98b3dd74368db366",
"title": "95b1b715d8351bd09dc1c4af98b3dd74368db366",
"url": "https://chromium.googlesource.com/chromium/src/+/95b1b715d8351bd09dc1c4af98b3dd74368db366"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fadf7a74fe308f3520fd931de0bcbd51102a8c07"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@fadf7a74fe308f3520fd931de0bcbd51102a8c07",
"title": "fadf7a74fe308f3520fd931de0bcbd51102a8c07",
"url": "https://chromium.googlesource.com/chromium/src/+/fadf7a74fe308f3520fd931de0bcbd51102a8c07"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4f205466524eec3ee9c8af1f43f027ef67f76a17"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@4f205466524eec3ee9c8af1f43f027ef67f76a17",
"title": "4f205466524eec3ee9c8af1f43f027ef67f76a17",
"url": "https://chromium.googlesource.com/chromium/src/+/4f205466524eec3ee9c8af1f43f027ef67f76a17"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "307a39f845885c5b87022dcb1d43f73ab5a520e5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@307a39f845885c5b87022dcb1d43f73ab5a520e5",
"title": "307a39f845885c5b87022dcb1d43f73ab5a520e5",
"url": "https://chromium.googlesource.com/chromium/src/+/307a39f845885c5b87022dcb1d43f73ab5a520e5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ed8b501fdd53516f39e6853b16c2e6708556d00a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@ed8b501fdd53516f39e6853b16c2e6708556d00a",
"title": "ed8b501fdd53516f39e6853b16c2e6708556d00a",
"url": "https://chromium.googlesource.com/chromium/src/+/ed8b501fdd53516f39e6853b16c2e6708556d00a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "af936684fe6f1f8094e6e76355012688f4092703"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@af936684fe6f1f8094e6e76355012688f4092703",
"title": "af936684fe6f1f8094e6e76355012688f4092703",
"url": "https://chromium.googlesource.com/chromium/src/+/af936684fe6f1f8094e6e76355012688f4092703"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "504403ff5a16a894594573ea7b21c22baf227b3f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@504403ff5a16a894594573ea7b21c22baf227b3f",
"title": "504403ff5a16a894594573ea7b21c22baf227b3f",
"url": "https://chromium.googlesource.com/chromium/src/+/504403ff5a16a894594573ea7b21c22baf227b3f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "64e01e41ca548fd0fd2b9c149c995965a24eacd7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@64e01e41ca548fd0fd2b9c149c995965a24eacd7",
"title": "64e01e41ca548fd0fd2b9c149c995965a24eacd7",
"url": "https://chromium.googlesource.com/chromium/src/+/64e01e41ca548fd0fd2b9c149c995965a24eacd7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "33411a86e0225283b773df0bfc47f562ef6661ba"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@33411a86e0225283b773df0bfc47f562ef6661ba",
"title": "33411a86e0225283b773df0bfc47f562ef6661ba",
"url": "https://chromium.googlesource.com/chromium/src/+/33411a86e0225283b773df0bfc47f562ef6661ba"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "33411a86e0225283b773df0bfc47f562ef6661ba",
"ref": "refs/branch-heads/7680"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8854279852440149488"
},
{
"key": "scheduler_job_id",
"value": "chromium-m146/linux-cast-x64-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:02:18.230] Scheduled build:
{
"id": "8688849790598716481",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "linux-cast-x64-dbg"
},
"number": 272,
"createdBy": "project:chromium-m146",
"createTime": "2026-02-26T00:02:17.802653692Z",
"updateTime": "2026-02-26T00:02:17.802653692Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "33411a86e0225283b773df0bfc47f562ef6661ba",
"ref": "refs/branch-heads/7680"
}
}
}
[00:02:18.230] Task URL: https://cr-buildbucket.appspot.com/build/8688849790598716481
[00:02:18.230] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:2:0) after 8m36s
[00:02:18.822] Received PubSub notification, asking Buildbucket for the build status
[00:02:18.880] Build status: SCHEDULED
[00:02:23.543] Received PubSub notification, asking Buildbucket for the build status
[00:02:23.559] Build status: STARTED
[00:10:54.499] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:2:0)
[00:10:54.542] Build status: STARTED
[00:10:54.543] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:5:0) after 6m10s
[00:17:04.557] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:5:0)
[00:17:04.577] Build status: STARTED
[00:17:04.578] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:6:0) after 4m27s
[00:21:31.642] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:6:0)
[00:21:31.657] Build status: STARTED
[00:21:31.657] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:7:0) after 1m12s
[00:22:43.765] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:7:0)
[00:22:43.782] Build status: STARTED
[00:22:43.782] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:8:0) after 5m39s
[00:28:22.823] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:8:0)
[00:28:22.878] Build status: STARTED
[00:28:22.878] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:9:0) after 5m47s
[00:34:10.051] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:9:0)
[00:34:10.071] Build status: STARTED
[00:34:10.071] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:10:0) after 1m3s
[00:35:13.451] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:10:0)
[00:35:13.494] Build status: STARTED
[00:35:13.494] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:11:0) after 4m12s
[00:39:25.541] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:11:0)
[00:39:25.559] Build status: STARTED
[00:39:25.559] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:12:0) after 7m30s
[00:46:55.629] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:12:0)
[00:46:55.645] Build status: STARTED
[00:46:55.645] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:13:0) after 5m19s
[00:52:14.665] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:13:0)
[00:52:14.698] Build status: STARTED
[00:52:14.698] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:14:0) after 5m29s
[00:57:43.703] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:14:0)
[00:57:43.718] Build status: STARTED
[00:57:43.719] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:15:0) after 4m35s
[01:02:18.773] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:15:0)
[01:02:18.805] Build status: STARTED
[01:02:18.805] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:16:0) after 2m1s
[01:04:19.882] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:16:0)
[01:04:19.915] Build status: STARTED
[01:04:19.916] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:17:0) after 2m40s
[01:06:59.931] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:17:0)
[01:06:59.944] Build status: STARTED
[01:06:59.944] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:18:0) after 1m13s
[01:08:13.020] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:18:0)
[01:08:13.038] Build status: STARTED
[01:08:13.038] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:19:0) after 5m55s
[01:14:08.061] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:19:0)
[01:14:08.077] Build status: STARTED
[01:14:08.077] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:20:0) after 1m3s
[01:15:11.099] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:20:0)
[01:15:11.117] Build status: STARTED
[01:15:11.117] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:21:0) after 8m8s
[01:23:19.189] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:21:0)
[01:23:19.206] Build status: STARTED
[01:23:19.206] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:22:0) after 5m28s
[01:28:47.293] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:22:0)
[01:28:47.331] Build status: STARTED
[01:28:47.331] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:23:0) after 9m4s
[01:37:51.606] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:23:0)
[01:37:51.618] Build status: STARTED
[01:37:51.618] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:24:0) after 9m41s
[01:47:32.644] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:24:0)
[01:47:32.752] Build status: STARTED
[01:47:32.752] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:25:0) after 1m30s
[01:49:02.841] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:25:0)
[01:49:02.860] Build status: STARTED
[01:49:02.860] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:26:0) after 9m7s
[01:58:10.015] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:26:0)
[01:58:10.032] Build status: STARTED
[01:58:10.032] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:27:0) after 3m43s
[02:01:53.107] Handling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:27:0)
[02:01:53.123] Build status: STARTED
[02:01:53.123] Scheduling timer "check-buildbucket-build-status" (chromium-m146/linux-cast-x64-dbg:8854279852440149488:28:0) after 4m45s
[02:02:56.828] Received PubSub notification, asking Buildbucket for the build status
[02:02:56.848] Build:
{
"id": "8688849790598716481",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "linux-cast-x64-dbg"
},
"number": 272,
"createdBy": "project:chromium-m146",
"createTime": "2026-02-26T00:02:17.802653692Z",
"startTime": "2026-02-26T00:02:23.010814916Z",
"endTime": "2026-02-26T02:02:56.359423289Z",
"updateTime": "2026-02-26T02:02:56.359423289Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "33411a86e0225283b773df0bfc47f562ef6661ba",
"ref": "refs/branch-heads/7680"
}
}
}
[02:02:56.848] Invocation finished in 2h0m40.186160995s with status SUCCEEDED