[14:48:52.265] New invocation is queued and will start shortly
[14:48:53.412] Starting the invocation (attempt 1)
[14:48:53.435] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/99eda50cbe172a68f7b49161fa2145379c79c962"
[14:48:53.435] Popped gitiles commit info from properties and tags
[14:48:53.435] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:48:53.435] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:48:53.436] Buildbucket request:
{
"requestId": "8844077212576632048",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8844077212576632048",
"job": "chromium-m150/linux-chromeos-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "658dfb7456ae06f057d2a496fae9d6b7b484081f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@658dfb7456ae06f057d2a496fae9d6b7b484081f",
"title": "658dfb7456ae06f057d2a496fae9d6b7b484081f",
"url": "https://chromium.googlesource.com/chromium/src/+/658dfb7456ae06f057d2a496fae9d6b7b484081f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d07e8c8343b1196c83044a2e8326b84c8d7382c8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@d07e8c8343b1196c83044a2e8326b84c8d7382c8",
"title": "d07e8c8343b1196c83044a2e8326b84c8d7382c8",
"url": "https://chromium.googlesource.com/chromium/src/+/d07e8c8343b1196c83044a2e8326b84c8d7382c8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b7f9b51da22400cb2584c4d67ac6235ad7681f6c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@b7f9b51da22400cb2584c4d67ac6235ad7681f6c",
"title": "b7f9b51da22400cb2584c4d67ac6235ad7681f6c",
"url": "https://chromium.googlesource.com/chromium/src/+/b7f9b51da22400cb2584c4d67ac6235ad7681f6c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "787494954df8f06f19e2110fe1447259bf110bd0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@787494954df8f06f19e2110fe1447259bf110bd0",
"title": "787494954df8f06f19e2110fe1447259bf110bd0",
"url": "https://chromium.googlesource.com/chromium/src/+/787494954df8f06f19e2110fe1447259bf110bd0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "99eda50cbe172a68f7b49161fa2145379c79c962"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@99eda50cbe172a68f7b49161fa2145379c79c962",
"title": "99eda50cbe172a68f7b49161fa2145379c79c962",
"url": "https://chromium.googlesource.com/chromium/src/+/99eda50cbe172a68f7b49161fa2145379c79c962"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "99eda50cbe172a68f7b49161fa2145379c79c962",
"ref": "refs/branch-heads/7871"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8844077212576632048"
},
{
"key": "scheduler_job_id",
"value": "chromium-m150/linux-chromeos-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:48:53.943] Scheduled build:
{
"id": "8678647150627254449",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 160,
"createdBy": "project:chromium-m150",
"createTime": "2026-06-18T14:48:53.502998334Z",
"updateTime": "2026-06-18T14:48:53.502998334Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "99eda50cbe172a68f7b49161fa2145379c79c962",
"ref": "refs/branch-heads/7871"
}
}
}
[14:48:53.943] Task URL: https://cr-buildbucket.appspot.com/build/8678647150627254449
[14:48:53.943] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:2:0) after 1m0s
[14:48:54.879] Received PubSub notification, asking Buildbucket for the build status
[14:48:54.897] Build status: SCHEDULED
[14:49:48.756] Received PubSub notification, asking Buildbucket for the build status
[14:49:48.848] Build status: STARTED
[14:49:53.966] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:2:0)
[14:49:53.982] Build status: STARTED
[14:49:53.982] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:5:0) after 5m20s
[14:55:14.016] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:5:0)
[14:55:14.032] Build status: STARTED
[14:55:14.032] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:6:0) after 7m0s
[15:02:14.040] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:6:0)
[15:02:14.062] Build status: STARTED
[15:02:14.062] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:7:0) after 6m2s
[15:08:16.107] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:7:0)
[15:08:16.126] Build status: STARTED
[15:08:16.126] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:8:0) after 7m54s
[15:16:10.200] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:8:0)
[15:16:10.229] Build status: STARTED
[15:16:10.229] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:9:0) after 5m56s
[15:22:06.357] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:9:0)
[15:22:06.385] Build status: STARTED
[15:22:06.385] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:10:0) after 5m14s
[15:27:20.530] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:10:0)
[15:27:20.560] Build status: STARTED
[15:27:20.560] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:11:0) after 1m26s
[15:28:46.623] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:11:0)
[15:28:46.644] Build status: STARTED
[15:28:46.644] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:12:0) after 9m47s
[15:38:33.837] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:12:0)
[15:38:33.865] Build status: STARTED
[15:38:33.865] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:13:0) after 6m13s
[15:44:46.929] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:13:0)
[15:44:46.965] Build status: STARTED
[15:44:46.965] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:14:0) after 9m44s
[15:54:31.079] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:14:0)
[15:54:31.097] Build status: STARTED
[15:54:31.097] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:15:0) after 6m6s
[16:00:37.147] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:15:0)
[16:00:37.191] Build status: STARTED
[16:00:37.191] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:16:0) after 6m59s
[16:07:36.522] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:16:0)
[16:07:36.548] Build status: STARTED
[16:07:36.548] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:17:0) after 1m21s
[16:08:57.621] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:17:0)
[16:08:57.640] Build status: STARTED
[16:08:57.640] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:18:0) after 3m44s
[16:12:41.732] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:18:0)
[16:12:41.751] Build status: STARTED
[16:12:41.751] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:19:0) after 3m50s
[16:16:31.888] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:19:0)
[16:16:31.903] Build status: STARTED
[16:16:31.903] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:20:0) after 7m24s
[16:23:55.979] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:20:0)
[16:23:55.996] Build status: STARTED
[16:23:55.996] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:21:0) after 4m34s
[16:28:30.244] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:21:0)
[16:28:30.317] Build status: STARTED
[16:28:30.317] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:22:0) after 1m32s
[16:30:02.340] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:22:0)
[16:30:02.446] Build status: STARTED
[16:30:02.446] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:23:0) after 4m36s
[16:34:38.460] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:23:0)
[16:34:38.494] Build status: STARTED
[16:34:38.494] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:24:0) after 2m41s
[16:37:19.501] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:24:0)
[16:37:19.520] Build status: STARTED
[16:37:19.520] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:25:0) after 4m19s
[16:41:38.510] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:25:0)
[16:41:38.528] Build status: STARTED
[16:41:38.528] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:26:0) after 5m59s
[16:47:37.519] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:26:0)
[16:47:37.632] Build status: STARTED
[16:47:37.633] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:27:0) after 7m17s
[16:54:54.649] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:27:0)
[16:54:54.672] Build status: STARTED
[16:54:54.672] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:28:0) after 3m47s
[16:58:41.738] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:28:0)
[16:58:41.915] Build status: STARTED
[16:58:41.915] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:29:0) after 7m32s
[17:06:14.036] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:29:0)
[17:06:14.052] Build status: STARTED
[17:06:14.052] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:30:0) after 3m49s
[17:10:03.062] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:30:0)
[17:10:03.082] Build status: STARTED
[17:10:03.082] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:31:0) after 2m36s
[17:12:39.107] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:31:0)
[17:12:39.141] Build status: STARTED
[17:12:39.142] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:32:0) after 6m41s
[17:19:20.208] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:32:0)
[17:19:20.234] Build status: STARTED
[17:19:20.234] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-chromeos-dbg:8844077212576632048:33:0) after 3m25s
[17:21:41.386] Received PubSub notification, asking Buildbucket for the build status
[17:21:41.412] Build:
{
"id": "8678647150627254449",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 160,
"createdBy": "project:chromium-m150",
"createTime": "2026-06-18T14:48:53.502998334Z",
"startTime": "2026-06-18T14:49:48.578893582Z",
"endTime": "2026-06-18T17:21:41.006944654Z",
"updateTime": "2026-06-18T17:21:41.006944654Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "99eda50cbe172a68f7b49161fa2145379c79c962",
"ref": "refs/branch-heads/7871"
}
}
}
[17:21:41.412] Invocation finished in 2h32m49.152751981s with status FAILED