[12:26:36.696] New invocation is queued and will start shortly
[12:26:37.978] Starting the invocation (attempt 1)
[12:26:38.002] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e6b3cd912b2a1e5899daff30e07f0286e8fd330b"
[12:26:38.002] Popped gitiles commit info from properties and tags
[12:26:38.002] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:26:38.002] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:26:38.003] Buildbucket request:
{
"requestId": "8863655107513974416",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8863655107513974416",
"job": "chromium-m143/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "661466bf107906cb8564a988d9f562b37a2d15b7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@661466bf107906cb8564a988d9f562b37a2d15b7",
"title": "661466bf107906cb8564a988d9f562b37a2d15b7",
"url": "https://chromium.googlesource.com/chromium/src/+/661466bf107906cb8564a988d9f562b37a2d15b7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "376176ef7ad52720d94ee4b85c4c09a4699a7d73"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@376176ef7ad52720d94ee4b85c4c09a4699a7d73",
"title": "376176ef7ad52720d94ee4b85c4c09a4699a7d73",
"url": "https://chromium.googlesource.com/chromium/src/+/376176ef7ad52720d94ee4b85c4c09a4699a7d73"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "89edc380088d435812b3179c5d4cc6e6e11187e7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@89edc380088d435812b3179c5d4cc6e6e11187e7",
"title": "89edc380088d435812b3179c5d4cc6e6e11187e7",
"url": "https://chromium.googlesource.com/chromium/src/+/89edc380088d435812b3179c5d4cc6e6e11187e7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bb74ef4b88fe99d56f86ea03590377ac9d5ba57b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@bb74ef4b88fe99d56f86ea03590377ac9d5ba57b",
"title": "bb74ef4b88fe99d56f86ea03590377ac9d5ba57b",
"url": "https://chromium.googlesource.com/chromium/src/+/bb74ef4b88fe99d56f86ea03590377ac9d5ba57b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7083cb3457d94226bcec616e98abb947a81b54f5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@7083cb3457d94226bcec616e98abb947a81b54f5",
"title": "7083cb3457d94226bcec616e98abb947a81b54f5",
"url": "https://chromium.googlesource.com/chromium/src/+/7083cb3457d94226bcec616e98abb947a81b54f5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3a4e0ee0e14b105b0106a77aab8536b41a3bf0fc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@3a4e0ee0e14b105b0106a77aab8536b41a3bf0fc",
"title": "3a4e0ee0e14b105b0106a77aab8536b41a3bf0fc",
"url": "https://chromium.googlesource.com/chromium/src/+/3a4e0ee0e14b105b0106a77aab8536b41a3bf0fc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b5a5aa30e2ae05cb7aa0209b2eafab04f094520e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@b5a5aa30e2ae05cb7aa0209b2eafab04f094520e",
"title": "b5a5aa30e2ae05cb7aa0209b2eafab04f094520e",
"url": "https://chromium.googlesource.com/chromium/src/+/b5a5aa30e2ae05cb7aa0209b2eafab04f094520e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "48d1c9f1757ef0118e28e818a03c59f106801545"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@48d1c9f1757ef0118e28e818a03c59f106801545",
"title": "48d1c9f1757ef0118e28e818a03c59f106801545",
"url": "https://chromium.googlesource.com/chromium/src/+/48d1c9f1757ef0118e28e818a03c59f106801545"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9bc595a85f7f0e2958d187e417fcc890faf3c709"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@9bc595a85f7f0e2958d187e417fcc890faf3c709",
"title": "9bc595a85f7f0e2958d187e417fcc890faf3c709",
"url": "https://chromium.googlesource.com/chromium/src/+/9bc595a85f7f0e2958d187e417fcc890faf3c709"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "762c8a8ab17393c72ac5bbd115451b3ca6c168ed"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@762c8a8ab17393c72ac5bbd115451b3ca6c168ed",
"title": "762c8a8ab17393c72ac5bbd115451b3ca6c168ed",
"url": "https://chromium.googlesource.com/chromium/src/+/762c8a8ab17393c72ac5bbd115451b3ca6c168ed"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bce95043e01af1206dd712ef8317cd1688718f15"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@bce95043e01af1206dd712ef8317cd1688718f15",
"title": "bce95043e01af1206dd712ef8317cd1688718f15",
"url": "https://chromium.googlesource.com/chromium/src/+/bce95043e01af1206dd712ef8317cd1688718f15"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "af9a212518ba8477b5aa9f164c2375d83f91d318"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@af9a212518ba8477b5aa9f164c2375d83f91d318",
"title": "af9a212518ba8477b5aa9f164c2375d83f91d318",
"url": "https://chromium.googlesource.com/chromium/src/+/af9a212518ba8477b5aa9f164c2375d83f91d318"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e6b3cd912b2a1e5899daff30e07f0286e8fd330b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@e6b3cd912b2a1e5899daff30e07f0286e8fd330b",
"title": "e6b3cd912b2a1e5899daff30e07f0286e8fd330b",
"url": "https://chromium.googlesource.com/chromium/src/+/e6b3cd912b2a1e5899daff30e07f0286e8fd330b"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e6b3cd912b2a1e5899daff30e07f0286e8fd330b",
"ref": "refs/branch-heads/7499"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8863655107513974416"
},
{
"key": "scheduler_job_id",
"value": "chromium-m143/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:26:38.583] Scheduled build:
{
"id": "8698225045348734337",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "mac-official"
},
"number": 135,
"createdBy": "project:chromium-m143",
"createTime": "2025-11-14T12:26:38.137384552Z",
"updateTime": "2025-11-14T12:26:38.137384552Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e6b3cd912b2a1e5899daff30e07f0286e8fd330b",
"ref": "refs/branch-heads/7499"
}
}
}
[12:26:38.583] Task URL: https://cr-buildbucket.appspot.com/build/8698225045348734337
[12:26:38.583] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:2:0) after 1m30s
[12:26:39.586] Received PubSub notification, asking Buildbucket for the build status
[12:26:39.734] Build status: SCHEDULED
[12:26:41.789] Received PubSub notification, asking Buildbucket for the build status
[12:26:41.803] Build status: STARTED
[12:28:08.714] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:2:0)
[12:28:08.766] Build status: STARTED
[12:28:08.766] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:5:0) after 5m57s
[12:34:05.823] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:5:0)
[12:34:05.884] Build status: STARTED
[12:34:05.884] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:6:0) after 4m34s
[12:38:40.034] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:6:0)
[12:38:40.093] Build status: STARTED
[12:38:40.093] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:7:0) after 1m12s
[12:39:52.138] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:7:0)
[12:39:52.201] Build status: STARTED
[12:39:52.202] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:8:0) after 2m2s
[12:41:54.433] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:8:0)
[12:41:54.495] Build status: STARTED
[12:41:54.495] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:9:0) after 7m3s
[12:48:57.614] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:9:0)
[12:48:57.632] Build status: STARTED
[12:48:57.632] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:10:0) after 8m19s
[12:57:16.652] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:10:0)
[12:57:16.674] Build status: STARTED
[12:57:16.674] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:11:0) after 9m51s
[13:07:07.740] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:11:0)
[13:07:07.756] Build status: STARTED
[13:07:07.756] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:12:0) after 8m46s
[13:15:53.763] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:12:0)
[13:15:53.780] Build status: STARTED
[13:15:53.780] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:13:0) after 9m40s
[13:25:33.837] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:13:0)
[13:25:33.855] Build status: STARTED
[13:25:33.855] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:14:0) after 1m52s
[13:27:25.921] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:14:0)
[13:27:25.938] Build status: STARTED
[13:27:25.938] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:15:0) after 3m18s
[13:30:43.951] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:15:0)
[13:30:43.967] Build status: STARTED
[13:30:43.967] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:16:0) after 1m52s
[13:32:35.986] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:16:0)
[13:32:36.001] Build status: STARTED
[13:32:36.001] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:17:0) after 1m51s
[13:34:27.115] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:17:0)
[13:34:27.129] Build status: STARTED
[13:34:27.129] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:18:0) after 2m52s
[13:37:19.177] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:18:0)
[13:37:19.193] Build status: STARTED
[13:37:19.193] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:19:0) after 4m59s
[13:42:18.259] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:19:0)
[13:42:18.276] Build status: STARTED
[13:42:18.276] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:20:0) after 1m19s
[13:43:37.291] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:20:0)
[13:43:37.357] Build status: STARTED
[13:43:37.357] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:21:0) after 1m37s
[13:45:14.452] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:21:0)
[13:45:14.502] Build status: STARTED
[13:45:14.502] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:22:0) after 6m16s
[13:51:30.521] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:22:0)
[13:51:30.536] Build status: STARTED
[13:51:30.536] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:23:0) after 7m2s
[13:58:32.552] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:23:0)
[13:58:32.568] Build status: STARTED
[13:58:32.568] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:24:0) after 9m21s
[14:07:53.587] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:24:0)
[14:07:53.631] Build status: STARTED
[14:07:53.631] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:25:0) after 5m43s
[14:13:36.663] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:25:0)
[14:13:36.700] Build status: STARTED
[14:13:36.700] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:26:0) after 7m28s
[14:21:04.715] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:26:0)
[14:21:04.731] Build status: STARTED
[14:21:04.731] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:27:0) after 5m31s
[14:26:35.752] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:27:0)
[14:26:35.826] Build status: STARTED
[14:26:35.826] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:28:0) after 2m41s
[14:29:16.844] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:28:0)
[14:29:16.862] Build status: STARTED
[14:29:16.862] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:29:0) after 2m14s
[14:31:30.872] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:29:0)
[14:31:30.951] Build status: STARTED
[14:31:30.951] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:30:0) after 7m25s
[14:38:55.943] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:30:0)
[14:38:55.963] Build status: STARTED
[14:38:55.963] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:31:0) after 8m56s
[14:47:51.984] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:31:0)
[14:47:53.006] Build status: STARTED
[14:47:53.006] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:32:0) after 5m17s
[14:53:10.024] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:32:0)
[14:53:10.077] Build status: STARTED
[14:53:10.077] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:33:0) after 5m8s
[14:58:18.094] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:33:0)
[14:58:18.107] Build status: STARTED
[14:58:18.107] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:34:0) after 4m42s
[15:03:00.123] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:34:0)
[15:03:00.139] Build status: STARTED
[15:03:00.139] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:35:0) after 3m33s
[15:06:33.204] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:35:0)
[15:06:33.257] Build status: STARTED
[15:06:33.257] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:36:0) after 8m52s
[15:15:25.246] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:36:0)
[15:15:25.298] Build status: STARTED
[15:15:25.298] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:37:0) after 4m0s
[15:19:25.377] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:37:0)
[15:19:25.395] Build status: STARTED
[15:19:25.395] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:38:0) after 7m8s
[15:26:33.462] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:38:0)
[15:26:33.500] Build status: STARTED
[15:26:33.500] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8863655107513974416:39:0) after 7m59s
[15:29:24.611] Received PubSub notification, asking Buildbucket for the build status
[15:29:24.627] Build:
{
"id": "8698225045348734337",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "mac-official"
},
"number": 135,
"createdBy": "project:chromium-m143",
"createTime": "2025-11-14T12:26:38.137384552Z",
"startTime": "2025-11-14T12:26:41.606232417Z",
"endTime": "2025-11-14T15:29:24.223839044Z",
"updateTime": "2025-11-14T15:29:24.223839044Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e6b3cd912b2a1e5899daff30e07f0286e8fd330b",
"ref": "refs/branch-heads/7499"
}
}
}
[15:29:24.627] Invocation finished in 3h2m47.939190702s with status SUCCEEDED