[14:20:18.895] New invocation is queued and will start shortly
[14:20:20.004] Starting the invocation (attempt 1)
[14:20:20.043] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/77b5fe784d4a0c0164426096118301a69bc72682"
[14:20:20.043] Popped gitiles commit info from properties and tags
[14:20:20.044] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:20:20.045] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:20:20.046] Buildbucket request:
{
"requestId": "8850420796830655808",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8850420796830655808",
"job": "chromium-m148/linux-chromeos-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "439e893892cfbe5c993500b22c8714c04e47cb9a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@439e893892cfbe5c993500b22c8714c04e47cb9a",
"title": "439e893892cfbe5c993500b22c8714c04e47cb9a",
"url": "https://chromium.googlesource.com/chromium/src/+/439e893892cfbe5c993500b22c8714c04e47cb9a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5f1eb9b3782e20c416bb4640d9b97669123f3d41"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@5f1eb9b3782e20c416bb4640d9b97669123f3d41",
"title": "5f1eb9b3782e20c416bb4640d9b97669123f3d41",
"url": "https://chromium.googlesource.com/chromium/src/+/5f1eb9b3782e20c416bb4640d9b97669123f3d41"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ca07af1c8ad780891240146e3812466ae0e9eef9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@ca07af1c8ad780891240146e3812466ae0e9eef9",
"title": "ca07af1c8ad780891240146e3812466ae0e9eef9",
"url": "https://chromium.googlesource.com/chromium/src/+/ca07af1c8ad780891240146e3812466ae0e9eef9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "60a7c4a766761417317c41d6624cdc3aead5c111"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@60a7c4a766761417317c41d6624cdc3aead5c111",
"title": "60a7c4a766761417317c41d6624cdc3aead5c111",
"url": "https://chromium.googlesource.com/chromium/src/+/60a7c4a766761417317c41d6624cdc3aead5c111"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "37611f51ba455de1c8cdb44e20768e9e3515c9f7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@37611f51ba455de1c8cdb44e20768e9e3515c9f7",
"title": "37611f51ba455de1c8cdb44e20768e9e3515c9f7",
"url": "https://chromium.googlesource.com/chromium/src/+/37611f51ba455de1c8cdb44e20768e9e3515c9f7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9712279cbab6d51105698fd626e1cbec988f8b3b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@9712279cbab6d51105698fd626e1cbec988f8b3b",
"title": "9712279cbab6d51105698fd626e1cbec988f8b3b",
"url": "https://chromium.googlesource.com/chromium/src/+/9712279cbab6d51105698fd626e1cbec988f8b3b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "68e94e36fab7147646aee1acf77e9d2abafda0ed"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@68e94e36fab7147646aee1acf77e9d2abafda0ed",
"title": "68e94e36fab7147646aee1acf77e9d2abafda0ed",
"url": "https://chromium.googlesource.com/chromium/src/+/68e94e36fab7147646aee1acf77e9d2abafda0ed"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "22f5e6a635153232a0f63a28393cb0571203cf39"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@22f5e6a635153232a0f63a28393cb0571203cf39",
"title": "22f5e6a635153232a0f63a28393cb0571203cf39",
"url": "https://chromium.googlesource.com/chromium/src/+/22f5e6a635153232a0f63a28393cb0571203cf39"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a68b50da6ba38d9df253b653b660c505135ad2fc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@a68b50da6ba38d9df253b653b660c505135ad2fc",
"title": "a68b50da6ba38d9df253b653b660c505135ad2fc",
"url": "https://chromium.googlesource.com/chromium/src/+/a68b50da6ba38d9df253b653b660c505135ad2fc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c020b9112dd5293034a6da05dea447274f983d37"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@c020b9112dd5293034a6da05dea447274f983d37",
"title": "c020b9112dd5293034a6da05dea447274f983d37",
"url": "https://chromium.googlesource.com/chromium/src/+/c020b9112dd5293034a6da05dea447274f983d37"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2e1ccde57f0e62e0839e8b86b9d0fe88348d1f22"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@2e1ccde57f0e62e0839e8b86b9d0fe88348d1f22",
"title": "2e1ccde57f0e62e0839e8b86b9d0fe88348d1f22",
"url": "https://chromium.googlesource.com/chromium/src/+/2e1ccde57f0e62e0839e8b86b9d0fe88348d1f22"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "77b5fe784d4a0c0164426096118301a69bc72682"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@77b5fe784d4a0c0164426096118301a69bc72682",
"title": "77b5fe784d4a0c0164426096118301a69bc72682",
"url": "https://chromium.googlesource.com/chromium/src/+/77b5fe784d4a0c0164426096118301a69bc72682"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "77b5fe784d4a0c0164426096118301a69bc72682",
"ref": "refs/branch-heads/7778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8850420796830655808"
},
{
"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"
}
}
[14:20:20.974] Scheduled build:
{
"id": "8684990734898343585",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 22,
"createdBy": "project:chromium-m148",
"createTime": "2026-04-09T14:20:20.109791596Z",
"updateTime": "2026-04-09T14:20:20.109791596Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "77b5fe784d4a0c0164426096118301a69bc72682",
"ref": "refs/branch-heads/7778"
}
}
}
[14:20:20.986] Task URL: https://cr-buildbucket.appspot.com/build/8684990734898343585
[14:20:20.986] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:2:0) after 5m51s
[14:20:21.766] Received PubSub notification, asking Buildbucket for the build status
[14:20:21.787] Build status: SCHEDULED
[14:21:09.098] Received PubSub notification, asking Buildbucket for the build status
[14:21:09.118] Build status: STARTED
[14:26:12.007] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:2:0)
[14:26:12.035] Build status: STARTED
[14:26:12.036] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:5:0) after 1m51s
[14:28:03.200] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:5:0)
[14:28:03.225] Build status: STARTED
[14:28:03.225] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:6:0) after 8m9s
[14:36:12.250] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:6:0)
[14:36:12.274] Build status: STARTED
[14:36:12.274] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:7:0) after 9m43s
[14:45:55.377] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:7:0)
[14:45:55.403] Build status: STARTED
[14:45:55.403] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:8:0) after 2m53s
[14:48:48.431] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:8:0)
[14:48:48.453] Build status: STARTED
[14:48:48.453] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:9:0) after 3m38s
[14:52:26.509] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:9:0)
[14:52:26.530] Build status: STARTED
[14:52:26.530] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:10:0) after 5m20s
[14:57:46.546] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:10:0)
[14:57:46.575] Build status: STARTED
[14:57:46.575] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:11:0) after 8m41s
[15:06:27.693] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:11:0)
[15:06:27.788] Build status: STARTED
[15:06:27.788] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:12:0) after 1m55s
[15:08:22.803] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:12:0)
[15:08:22.822] Build status: STARTED
[15:08:22.823] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:13:0) after 1m2s
[15:09:24.844] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:13:0)
[15:09:24.861] Build status: STARTED
[15:09:24.861] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:14:0) after 1m48s
[15:11:12.852] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:14:0)
[15:11:12.906] Build status: STARTED
[15:11:12.906] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:15:0) after 5m41s
[15:16:53.926] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:15:0)
[15:16:53.952] Build status: STARTED
[15:16:53.952] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:16:0) after 8m41s
[15:25:35.020] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:16:0)
[15:25:35.049] Build status: STARTED
[15:25:35.049] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:17:0) after 4m47s
[15:30:22.057] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:17:0)
[15:30:22.087] Build status: STARTED
[15:30:22.088] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:18:0) after 1m15s
[15:31:37.131] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:18:0)
[15:31:37.150] Build status: STARTED
[15:31:37.150] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:19:0) after 3m12s
[15:34:49.168] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:19:0)
[15:34:49.317] Build status: STARTED
[15:34:49.317] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:20:0) after 4m45s
[15:39:34.448] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:20:0)
[15:39:34.471] Build status: STARTED
[15:39:34.471] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:21:0) after 7m3s
[15:46:37.576] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:21:0)
[15:46:37.609] Build status: STARTED
[15:46:37.609] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:22:0) after 4m17s
[15:50:54.665] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:22:0)
[15:50:54.690] Build status: STARTED
[15:50:54.690] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:23:0) after 9m54s
[16:00:49.078] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:23:0)
[16:00:49.104] Build status: STARTED
[16:00:49.104] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:24:0) after 4m35s
[16:05:24.195] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:24:0)
[16:05:24.342] Build status: STARTED
[16:05:24.342] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:25:0) after 8m29s
[16:13:53.363] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:25:0)
[16:13:53.388] Build status: STARTED
[16:13:53.388] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:26:0) after 9m55s
[16:23:48.402] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:26:0)
[16:23:48.419] Build status: STARTED
[16:23:48.419] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:27:0) after 2m59s
[16:26:47.520] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:27:0)
[16:26:47.549] Build status: STARTED
[16:26:47.549] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:28:0) after 2m55s
[16:29:42.567] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:28:0)
[16:29:42.586] Build status: STARTED
[16:29:42.586] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:29:0) after 1m6s
[16:30:48.598] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:29:0)
[16:30:48.668] Build status: STARTED
[16:30:48.668] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:30:0) after 8m1s
[16:38:49.724] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:30:0)
[16:38:49.743] Build status: STARTED
[16:38:49.743] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:31:0) after 4m59s
[16:43:48.801] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:31:0)
[16:43:48.836] Build status: STARTED
[16:43:48.836] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-chromeos-dbg:8850420796830655808:32:0) after 2m57s
[16:45:28.630] Received PubSub notification, asking Buildbucket for the build status
[16:45:28.739] Build:
{
"id": "8684990734898343585",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 22,
"createdBy": "project:chromium-m148",
"createTime": "2026-04-09T14:20:20.109791596Z",
"startTime": "2026-04-09T14:21:08.919347749Z",
"endTime": "2026-04-09T16:45:14.355071228Z",
"updateTime": "2026-04-09T16:45:14.355071228Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "77b5fe784d4a0c0164426096118301a69bc72682",
"ref": "refs/branch-heads/7778"
}
}
}
[16:45:28.739] Invocation finished in 2h25m9.857251489s with status SUCCEEDED