[05:21:02.468] New invocation is queued and will start shortly
[05:21:03.655] Starting the invocation (attempt 1)
[05:21:03.676] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5f0bd3c02a6699b497e0a31603771dbe55680f9d"
[05:21:03.676] Popped gitiles commit info from properties and tags
[05:21:03.676] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:21:03.677] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:21:03.677] Buildbucket request:
{
"requestId": "8857883676248122992",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "android-desktop-arm64-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8857883676248122992",
"job": "chromium-m145/android-desktop-arm64-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1e0d63917e37ae99b657ef2f98b93abc7789b0a0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@1e0d63917e37ae99b657ef2f98b93abc7789b0a0",
"title": "1e0d63917e37ae99b657ef2f98b93abc7789b0a0",
"url": "https://chromium.googlesource.com/chromium/src/+/1e0d63917e37ae99b657ef2f98b93abc7789b0a0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e061055661194ae43798d8113eb870ca7755ead2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@e061055661194ae43798d8113eb870ca7755ead2",
"title": "e061055661194ae43798d8113eb870ca7755ead2",
"url": "https://chromium.googlesource.com/chromium/src/+/e061055661194ae43798d8113eb870ca7755ead2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "db2c5676a9abc38c4266c5ae84c09d7b16bb3599"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@db2c5676a9abc38c4266c5ae84c09d7b16bb3599",
"title": "db2c5676a9abc38c4266c5ae84c09d7b16bb3599",
"url": "https://chromium.googlesource.com/chromium/src/+/db2c5676a9abc38c4266c5ae84c09d7b16bb3599"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "66ca297d78699fe234aaf3f6cc3d174d9d9e514b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@66ca297d78699fe234aaf3f6cc3d174d9d9e514b",
"title": "66ca297d78699fe234aaf3f6cc3d174d9d9e514b",
"url": "https://chromium.googlesource.com/chromium/src/+/66ca297d78699fe234aaf3f6cc3d174d9d9e514b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0c5839c882ef9b8dc9138a4987bf8e6347400792"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@0c5839c882ef9b8dc9138a4987bf8e6347400792",
"title": "0c5839c882ef9b8dc9138a4987bf8e6347400792",
"url": "https://chromium.googlesource.com/chromium/src/+/0c5839c882ef9b8dc9138a4987bf8e6347400792"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e64e4c4475c09b41df76fa66375d669b2869f5e0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@e64e4c4475c09b41df76fa66375d669b2869f5e0",
"title": "e64e4c4475c09b41df76fa66375d669b2869f5e0",
"url": "https://chromium.googlesource.com/chromium/src/+/e64e4c4475c09b41df76fa66375d669b2869f5e0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "66e52cca959695b0419926a820aa78e2d174cba7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@66e52cca959695b0419926a820aa78e2d174cba7",
"title": "66e52cca959695b0419926a820aa78e2d174cba7",
"url": "https://chromium.googlesource.com/chromium/src/+/66e52cca959695b0419926a820aa78e2d174cba7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4e57f345ed68fcb2bec7fa7ea567daac6ac9b3eb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@4e57f345ed68fcb2bec7fa7ea567daac6ac9b3eb",
"title": "4e57f345ed68fcb2bec7fa7ea567daac6ac9b3eb",
"url": "https://chromium.googlesource.com/chromium/src/+/4e57f345ed68fcb2bec7fa7ea567daac6ac9b3eb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "eed1bd391fc8ade7e4a9fc9beaefb6e13ea48e3b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@eed1bd391fc8ade7e4a9fc9beaefb6e13ea48e3b",
"title": "eed1bd391fc8ade7e4a9fc9beaefb6e13ea48e3b",
"url": "https://chromium.googlesource.com/chromium/src/+/eed1bd391fc8ade7e4a9fc9beaefb6e13ea48e3b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "14fc636e9eb25a7ab8b1af56bab1b3eb47034389"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@14fc636e9eb25a7ab8b1af56bab1b3eb47034389",
"title": "14fc636e9eb25a7ab8b1af56bab1b3eb47034389",
"url": "https://chromium.googlesource.com/chromium/src/+/14fc636e9eb25a7ab8b1af56bab1b3eb47034389"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "21b9a13c081ed92772d5c8099e227d21cc58fc7b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@21b9a13c081ed92772d5c8099e227d21cc58fc7b",
"title": "21b9a13c081ed92772d5c8099e227d21cc58fc7b",
"url": "https://chromium.googlesource.com/chromium/src/+/21b9a13c081ed92772d5c8099e227d21cc58fc7b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5f0bd3c02a6699b497e0a31603771dbe55680f9d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@5f0bd3c02a6699b497e0a31603771dbe55680f9d",
"title": "5f0bd3c02a6699b497e0a31603771dbe55680f9d",
"url": "https://chromium.googlesource.com/chromium/src/+/5f0bd3c02a6699b497e0a31603771dbe55680f9d"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5f0bd3c02a6699b497e0a31603771dbe55680f9d",
"ref": "refs/branch-heads/7632"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8857883676248122992"
},
{
"key": "scheduler_job_id",
"value": "chromium-m145/android-desktop-arm64-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:21:04.211] Scheduled build:
{
"id": "8692453614275373393",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "android-desktop-arm64-official"
},
"number": 27,
"createdBy": "project:chromium-m145",
"createTime": "2026-01-17T05:21:03.720688633Z",
"updateTime": "2026-01-17T05:21:03.720688633Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5f0bd3c02a6699b497e0a31603771dbe55680f9d",
"ref": "refs/branch-heads/7632"
}
}
}
[05:21:04.211] Task URL: https://cr-buildbucket.appspot.com/build/8692453614275373393
[05:21:04.212] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:2:0) after 2m18s
[05:21:05.072] Received PubSub notification, asking Buildbucket for the build status
[05:21:05.085] Build status: SCHEDULED
[05:23:22.227] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:2:0)
[05:23:22.249] Build status: SCHEDULED
[05:23:22.249] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:4:0) after 8m41s
[05:24:30.687] Received PubSub notification, asking Buildbucket for the build status
[05:24:30.704] Build status: STARTED
[05:32:03.275] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:4:0)
[05:32:03.298] Build status: STARTED
[05:32:03.298] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:6:0) after 3m56s
[05:35:59.321] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:6:0)
[05:35:59.341] Build status: STARTED
[05:35:59.341] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:7:0) after 6m46s
[05:42:45.398] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:7:0)
[05:42:45.422] Build status: STARTED
[05:42:45.422] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:8:0) after 1m20s
[05:44:05.484] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:8:0)
[05:44:05.500] Build status: STARTED
[05:44:05.501] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:9:0) after 6m37s
[05:50:42.520] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:9:0)
[05:50:42.539] Build status: STARTED
[05:50:42.539] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:10:0) after 2m52s
[05:53:34.590] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:10:0)
[05:53:34.606] Build status: STARTED
[05:53:34.606] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:11:0) after 6m43s
[06:00:17.747] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:11:0)
[06:00:17.777] Build status: STARTED
[06:00:17.777] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:12:0) after 8m8s
[06:08:26.030] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:12:0)
[06:08:26.045] Build status: STARTED
[06:08:26.045] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:13:0) after 2m54s
[06:11:20.864] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:13:0)
[06:11:20.928] Build status: STARTED
[06:11:20.928] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:14:0) after 5m8s
[06:16:29.226] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:14:0)
[06:16:29.237] Build status: STARTED
[06:16:29.237] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:15:0) after 3m4s
[06:19:33.773] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:15:0)
[06:19:33.786] Build status: STARTED
[06:19:33.786] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:16:0) after 6m59s
[06:26:32.921] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:16:0)
[06:26:32.958] Build status: STARTED
[06:26:32.958] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:17:0) after 9m16s
[06:35:48.957] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:17:0)
[06:35:48.982] Build status: STARTED
[06:35:48.982] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:18:0) after 3m40s
[06:39:29.086] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:18:0)
[06:39:29.119] Build status: STARTED
[06:39:29.119] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:19:0) after 1m44s
[06:41:13.225] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:19:0)
[06:41:13.244] Build status: STARTED
[06:41:13.244] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:20:0) after 2m51s
[06:44:04.291] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:20:0)
[06:44:04.313] Build status: STARTED
[06:44:04.313] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:21:0) after 5m17s
[06:49:21.490] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:21:0)
[06:49:21.508] Build status: STARTED
[06:49:21.508] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:22:0) after 6m43s
[06:56:04.617] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:22:0)
[06:56:04.785] Build status: STARTED
[06:56:04.786] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:23:0) after 1m25s
[06:57:29.800] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:23:0)
[06:57:29.821] Build status: STARTED
[06:57:29.821] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:24:0) after 6m48s
[07:04:17.934] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:24:0)
[07:04:17.951] Build status: STARTED
[07:04:17.951] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:25:0) after 7m20s
[07:11:38.044] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:25:0)
[07:11:38.113] Build status: STARTED
[07:11:38.113] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:26:0) after 5m59s
[07:17:37.741] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:26:0)
[07:17:37.768] Build status: STARTED
[07:17:37.768] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:27:0) after 1m5s
[07:18:42.905] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:27:0)
[07:18:42.921] Build status: STARTED
[07:18:42.921] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:28:0) after 3m15s
[07:21:58.365] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:28:0)
[07:21:58.385] Build status: STARTED
[07:21:58.385] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:29:0) after 6m9s
[07:28:07.403] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:29:0)
[07:28:07.521] Build status: STARTED
[07:28:07.521] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:30:0) after 9m36s
[07:37:43.735] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:30:0)
[07:37:43.848] Build status: STARTED
[07:37:43.848] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:31:0) after 2m23s
[07:40:07.258] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:31:0)
[07:40:07.275] Build status: STARTED
[07:40:07.275] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:32:0) after 8m8s
[07:48:15.654] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:32:0)
[07:48:15.680] Build status: STARTED
[07:48:15.680] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:33:0) after 7m4s
[07:55:19.708] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:33:0)
[07:55:19.751] Build status: STARTED
[07:55:19.751] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:34:0) after 6m7s
[08:01:26.768] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:34:0)
[08:01:26.846] Build status: STARTED
[08:01:26.846] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:35:0) after 1m1s
[08:02:27.909] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:35:0)
[08:02:27.987] Build status: STARTED
[08:02:27.987] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:36:0) after 4m47s
[08:07:15.312] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:36:0)
[08:07:15.328] Build status: STARTED
[08:07:15.328] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:37:0) after 1m56s
[08:09:11.844] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:37:0)
[08:09:11.920] Build status: STARTED
[08:09:11.920] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:38:0) after 6m1s
[08:15:12.936] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:38:0)
[08:15:12.952] Build status: STARTED
[08:15:12.952] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:39:0) after 6m5s
[08:21:17.969] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:39:0)
[08:21:17.986] Build status: STARTED
[08:21:17.986] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:40:0) after 3m7s
[08:24:25.002] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:40:0)
[08:24:25.134] Build status: STARTED
[08:24:25.135] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:41:0) after 1m5s
[08:25:30.187] Handling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:41:0)
[08:25:30.204] Build status: STARTED
[08:25:30.204] Scheduling timer "check-buildbucket-build-status" (chromium-m145/android-desktop-arm64-official:8857883676248122992:42:0) after 6m7s
[08:26:00.182] Received PubSub notification, asking Buildbucket for the build status
[08:26:00.197] Build:
{
"id": "8692453614275373393",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "android-desktop-arm64-official"
},
"number": 27,
"createdBy": "project:chromium-m145",
"createTime": "2026-01-17T05:21:03.720688633Z",
"startTime": "2026-01-17T05:24:30.464132884Z",
"endTime": "2026-01-17T08:25:59.754682566Z",
"updateTime": "2026-01-17T08:25:59.754682566Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5f0bd3c02a6699b497e0a31603771dbe55680f9d",
"ref": "refs/branch-heads/7632"
}
}
}
[08:26:00.197] Invocation finished in 3h4m57.741439682s with status SUCCEEDED