[20:57:25.825] New invocation is queued and will start shortly
[20:57:26.887] Starting the invocation (attempt 1)
[20:57:26.914] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5fdca22bee196688e32b47a19ad754f6a4db5d96"
[20:57:26.914] Popped gitiles commit info from properties and tags
[20:57:26.914] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:57:26.914] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:57:26.915] Buildbucket request:
{
"requestId": "8846500142919486624",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8846500142919486624",
"job": "chromium-m148/linux-chromeos-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5d14fdc0c5ff45c39f87292906b30bd743fa0809"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@5d14fdc0c5ff45c39f87292906b30bd743fa0809",
"title": "5d14fdc0c5ff45c39f87292906b30bd743fa0809",
"url": "https://chromium.googlesource.com/chromium/src/+/5d14fdc0c5ff45c39f87292906b30bd743fa0809"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "102ffc4ac49e05231b2a49bfc818f8e799802027"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@102ffc4ac49e05231b2a49bfc818f8e799802027",
"title": "102ffc4ac49e05231b2a49bfc818f8e799802027",
"url": "https://chromium.googlesource.com/chromium/src/+/102ffc4ac49e05231b2a49bfc818f8e799802027"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "130fcce99909a4ac9f8824208c21b942e0fb4148"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@130fcce99909a4ac9f8824208c21b942e0fb4148",
"title": "130fcce99909a4ac9f8824208c21b942e0fb4148",
"url": "https://chromium.googlesource.com/chromium/src/+/130fcce99909a4ac9f8824208c21b942e0fb4148"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1612af9a7b5f2a8294c6faa63200485310cf119d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@1612af9a7b5f2a8294c6faa63200485310cf119d",
"title": "1612af9a7b5f2a8294c6faa63200485310cf119d",
"url": "https://chromium.googlesource.com/chromium/src/+/1612af9a7b5f2a8294c6faa63200485310cf119d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "37febbc75c51ce2cecafb86a034ebd5555576824"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@37febbc75c51ce2cecafb86a034ebd5555576824",
"title": "37febbc75c51ce2cecafb86a034ebd5555576824",
"url": "https://chromium.googlesource.com/chromium/src/+/37febbc75c51ce2cecafb86a034ebd5555576824"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fb4485b09859552d81ee1145b59e3bf1dacabfa5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@fb4485b09859552d81ee1145b59e3bf1dacabfa5",
"title": "fb4485b09859552d81ee1145b59e3bf1dacabfa5",
"url": "https://chromium.googlesource.com/chromium/src/+/fb4485b09859552d81ee1145b59e3bf1dacabfa5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2a73ec273009b58125934458e059bf252426751b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@2a73ec273009b58125934458e059bf252426751b",
"title": "2a73ec273009b58125934458e059bf252426751b",
"url": "https://chromium.googlesource.com/chromium/src/+/2a73ec273009b58125934458e059bf252426751b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4484e06419a6a7b864bde54f1af84f320d2eef19"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@4484e06419a6a7b864bde54f1af84f320d2eef19",
"title": "4484e06419a6a7b864bde54f1af84f320d2eef19",
"url": "https://chromium.googlesource.com/chromium/src/+/4484e06419a6a7b864bde54f1af84f320d2eef19"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ffc4115f6f9d79bf1ee1d443eef9b616ee530366"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@ffc4115f6f9d79bf1ee1d443eef9b616ee530366",
"title": "ffc4115f6f9d79bf1ee1d443eef9b616ee530366",
"url": "https://chromium.googlesource.com/chromium/src/+/ffc4115f6f9d79bf1ee1d443eef9b616ee530366"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1a7b7746744fc808985d7bd2f213398b15102658"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@1a7b7746744fc808985d7bd2f213398b15102658",
"title": "1a7b7746744fc808985d7bd2f213398b15102658",
"url": "https://chromium.googlesource.com/chromium/src/+/1a7b7746744fc808985d7bd2f213398b15102658"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5fdca22bee196688e32b47a19ad754f6a4db5d96"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@5fdca22bee196688e32b47a19ad754f6a4db5d96",
"title": "5fdca22bee196688e32b47a19ad754f6a4db5d96",
"url": "https://chromium.googlesource.com/chromium/src/+/5fdca22bee196688e32b47a19ad754f6a4db5d96"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5fdca22bee196688e32b47a19ad754f6a4db5d96",
"ref": "refs/branch-heads/7778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8846500142919486624"
},
{
"key": "scheduler_job_id",
"value": "chromium-m148/linux-chromeos-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:57:27.410] Scheduled build:
{
"id": "8681070081067802385",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 496,
"createdBy": "project:chromium-m148",
"createTime": "2026-05-22T20:57:26.970212018Z",
"updateTime": "2026-05-22T20:57:26.970212018Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5fdca22bee196688e32b47a19ad754f6a4db5d96",
"ref": "refs/branch-heads/7778"
}
}
}
[20:57:27.410] Task URL: https://cr-buildbucket.appspot.com/build/8681070081067802385
[20:57:27.410] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:2:0) after 6m59s
[20:57:28.389] Received PubSub notification, asking Buildbucket for the build status
[20:57:28.410] Build status: SCHEDULED
[21:01:35.235] Received PubSub notification, asking Buildbucket for the build status
[21:01:35.255] Build status: STARTED
[21:04:26.435] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:2:0)
[21:04:26.453] Build status: STARTED
[21:04:26.454] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:5:0) after 8m21s
[21:12:47.466] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:5:0)
[21:12:47.482] Build status: STARTED
[21:12:47.482] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:6:0) after 7m37s
[21:20:24.495] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:6:0)
[21:20:24.569] Build status: STARTED
[21:20:24.569] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:7:0) after 2m56s
[21:23:20.586] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:7:0)
[21:23:20.608] Build status: STARTED
[21:23:20.608] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:8:0) after 4m8s
[21:27:28.759] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:8:0)
[21:27:28.906] Build status: STARTED
[21:27:28.906] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:9:0) after 9m41s
[21:37:09.928] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:9:0)
[21:37:09.943] Build status: STARTED
[21:37:09.943] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:10:0) after 1m2s
[21:38:11.980] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:10:0)
[21:38:12.002] Build status: STARTED
[21:38:12.002] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:11:0) after 4m55s
[21:43:07.016] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:11:0)
[21:43:07.034] Build status: STARTED
[21:43:07.035] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:12:0) after 1m26s
[21:44:33.053] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:12:0)
[21:44:33.077] Build status: STARTED
[21:44:33.077] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:13:0) after 5m26s
[21:49:59.180] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:13:0)
[21:49:59.258] Build status: STARTED
[21:49:59.258] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:14:0) after 7m50s
[21:57:49.320] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:14:0)
[21:57:49.351] Build status: STARTED
[21:57:49.351] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:15:0) after 3m18s
[22:01:07.354] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:15:0)
[22:01:07.380] Build status: STARTED
[22:01:07.380] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:16:0) after 3m8s
[22:04:15.363] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:16:0)
[22:04:15.407] Build status: STARTED
[22:04:15.407] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:17:0) after 7m34s
[22:11:49.556] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:17:0)
[22:11:49.579] Build status: STARTED
[22:11:49.579] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:18:0) after 6m15s
[22:18:04.615] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:18:0)
[22:18:04.633] Build status: STARTED
[22:18:04.633] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:19:0) after 1m16s
[22:19:20.649] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:19:0)
[22:19:20.672] Build status: STARTED
[22:19:20.672] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:20:0) after 5m22s
[22:24:42.877] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:20:0)
[22:24:42.994] Build status: STARTED
[22:24:42.994] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:21:0) after 8m0s
[22:32:43.009] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:21:0)
[22:32:43.034] Build status: STARTED
[22:32:43.034] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:22:0) after 3m54s
[22:36:37.056] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:22:0)
[22:36:37.075] Build status: STARTED
[22:36:37.075] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:23:0) after 2m30s
[22:39:07.041] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:23:0)
[22:39:07.061] Build status: STARTED
[22:39:07.061] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:24:0) after 2m26s
[22:41:33.118] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:24:0)
[22:41:33.141] Build status: STARTED
[22:41:33.141] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:25:0) after 2m49s
[22:44:22.156] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:25:0)
[22:44:22.171] Build status: STARTED
[22:44:22.171] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:26:0) after 7m56s
[22:52:18.187] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:26:0)
[22:52:18.205] Build status: STARTED
[22:52:18.205] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:27:0) after 3m39s
[22:55:57.230] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:27:0)
[22:55:57.246] Build status: STARTED
[22:55:57.246] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:28:0) after 7m54s
[23:03:51.259] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:28:0)
[23:03:51.279] Build status: STARTED
[23:03:51.279] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:29:0) after 2m59s
[23:06:50.295] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:29:0)
[23:06:50.319] Build status: STARTED
[23:06:50.319] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:30:0) after 1m50s
[23:08:40.359] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:30:0)
[23:08:40.391] Build status: STARTED
[23:08:40.391] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:31:0) after 1m1s
[23:09:41.405] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:31:0)
[23:09:41.426] Build status: STARTED
[23:09:41.426] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:32:0) after 1m48s
[23:11:29.491] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:32:0)
[23:11:29.505] Build status: STARTED
[23:11:29.505] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:33:0) after 1m33s
[23:13:02.601] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:33:0)
[23:13:02.618] Build status: STARTED
[23:13:02.618] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:34:0) after 2m55s
[23:15:57.632] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:34:0)
[23:15:57.651] Build status: STARTED
[23:15:57.651] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:35:0) after 4m41s
[23:20:38.666] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:35:0)
[23:20:38.711] Build status: STARTED
[23:20:38.711] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:36:0) after 9m24s
[23:30:02.812] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:36:0)
[23:30:02.831] Build status: STARTED
[23:30:02.831] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:37:0) after 5m13s
[23:35:15.870] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:37:0)
[23:35:15.898] Build status: STARTED
[23:35:15.898] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:38:0) after 7m14s
[23:42:29.990] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:38:0)
[23:42:30.004] Build status: STARTED
[23:42:30.004] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:39:0) after 3m6s
[23:45:36.071] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:39:0)
[23:45:36.099] Build status: STARTED
[23:45:36.099] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:40:0) after 4m20s
[23:49:56.116] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:40:0)
[23:49:56.141] Build status: STARTED
[23:49:56.141] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:41:0) after 4m53s
[23:54:49.206] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:41:0)
[23:54:49.230] Build status: STARTED
[23:54:49.230] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8846500142919486624:42:0) after 4m59s
[23:56:35.570] Received PubSub notification, asking Buildbucket for the build status
[23:56:35.592] Build:
{
"id": "8681070081067802385",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 496,
"createdBy": "project:chromium-m148",
"createTime": "2026-05-22T20:57:26.970212018Z",
"startTime": "2026-05-22T21:01:35.038137139Z",
"endTime": "2026-05-22T23:56:35.289068878Z",
"updateTime": "2026-05-22T23:56:35.289068878Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5fdca22bee196688e32b47a19ad754f6a4db5d96",
"ref": "refs/branch-heads/7778"
}
}
}
[23:56:35.592] Invocation finished in 2h59m9.770524982s with status FAILED