[10:56:03.782] New invocation is queued and will start shortly
[10:56:05.054] Starting the invocation (attempt 1)
[10:56:05.085] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/29a72700e1d9a26624210ede90de17370ce1b503"
[10:56:05.085] Popped gitiles commit info from properties and tags
[10:56:05.085] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:56:05.085] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:56:05.086] Buildbucket request:
{
"requestId": "8866288116359519760",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8866288116359519760",
"job": "chromium-m142/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "382be8f7d2ac7cbe6f3fa981a36ed45c16a8b4bb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@382be8f7d2ac7cbe6f3fa981a36ed45c16a8b4bb",
"title": "382be8f7d2ac7cbe6f3fa981a36ed45c16a8b4bb",
"url": "https://chromium.googlesource.com/chromium/src/+/382be8f7d2ac7cbe6f3fa981a36ed45c16a8b4bb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2d93afe9c7e62c9b25b066877988f402f111470a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@2d93afe9c7e62c9b25b066877988f402f111470a",
"title": "2d93afe9c7e62c9b25b066877988f402f111470a",
"url": "https://chromium.googlesource.com/chromium/src/+/2d93afe9c7e62c9b25b066877988f402f111470a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d5a9c710cf84d06adf1e28f7826469330156fcc6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@d5a9c710cf84d06adf1e28f7826469330156fcc6",
"title": "d5a9c710cf84d06adf1e28f7826469330156fcc6",
"url": "https://chromium.googlesource.com/chromium/src/+/d5a9c710cf84d06adf1e28f7826469330156fcc6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "af2523430dce1630d57de8fff443937463d5c70d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@af2523430dce1630d57de8fff443937463d5c70d",
"title": "af2523430dce1630d57de8fff443937463d5c70d",
"url": "https://chromium.googlesource.com/chromium/src/+/af2523430dce1630d57de8fff443937463d5c70d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e0346efcf974c07f26bb109353927bd1ae762977"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@e0346efcf974c07f26bb109353927bd1ae762977",
"title": "e0346efcf974c07f26bb109353927bd1ae762977",
"url": "https://chromium.googlesource.com/chromium/src/+/e0346efcf974c07f26bb109353927bd1ae762977"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "710ea761563daaf27380e8a40d2e1abc197558d9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@710ea761563daaf27380e8a40d2e1abc197558d9",
"title": "710ea761563daaf27380e8a40d2e1abc197558d9",
"url": "https://chromium.googlesource.com/chromium/src/+/710ea761563daaf27380e8a40d2e1abc197558d9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6b7329796c1118616ef337cdad6013bdd3e98f3e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@6b7329796c1118616ef337cdad6013bdd3e98f3e",
"title": "6b7329796c1118616ef337cdad6013bdd3e98f3e",
"url": "https://chromium.googlesource.com/chromium/src/+/6b7329796c1118616ef337cdad6013bdd3e98f3e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "336431b3627b8922df49dbbd9f118d39dd1b3444"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@336431b3627b8922df49dbbd9f118d39dd1b3444",
"title": "336431b3627b8922df49dbbd9f118d39dd1b3444",
"url": "https://chromium.googlesource.com/chromium/src/+/336431b3627b8922df49dbbd9f118d39dd1b3444"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "29a72700e1d9a26624210ede90de17370ce1b503"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@29a72700e1d9a26624210ede90de17370ce1b503",
"title": "29a72700e1d9a26624210ede90de17370ce1b503",
"url": "https://chromium.googlesource.com/chromium/src/+/29a72700e1d9a26624210ede90de17370ce1b503"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "29a72700e1d9a26624210ede90de17370ce1b503",
"ref": "refs/branch-heads/7444"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8866288116359519760"
},
{
"key": "scheduler_job_id",
"value": "chromium-m142/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[10:56:05.759] Scheduled build:
{
"id": "8700858054254357761",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "mac-official"
},
"number": 134,
"createdBy": "project:chromium-m142",
"createTime": "2025-10-16T10:56:05.169611877Z",
"updateTime": "2025-10-16T10:56:05.169611877Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "29a72700e1d9a26624210ede90de17370ce1b503",
"ref": "refs/branch-heads/7444"
}
}
}
[10:56:05.759] Task URL: https://cr-buildbucket.appspot.com/build/8700858054254357761
[10:56:05.759] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:2:0) after 5m50s
[10:56:06.680] Received PubSub notification, asking Buildbucket for the build status
[10:56:06.702] Build status: SCHEDULED
[10:56:09.052] Received PubSub notification, asking Buildbucket for the build status
[10:56:09.117] Build status: STARTED
[11:01:55.771] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:2:0)
[11:01:55.807] Build status: STARTED
[11:01:55.807] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:5:0) after 3m4s
[11:04:59.826] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:5:0)
[11:04:59.874] Build status: STARTED
[11:04:59.874] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:6:0) after 8m39s
[11:13:39.023] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:6:0)
[11:13:39.047] Build status: STARTED
[11:13:39.047] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:7:0) after 3m5s
[11:16:44.062] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:7:0)
[11:16:44.080] Build status: STARTED
[11:16:44.080] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:8:0) after 5m36s
[11:22:20.093] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:8:0)
[11:22:20.124] Build status: STARTED
[11:22:20.124] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:9:0) after 8m19s
[11:30:39.149] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:9:0)
[11:30:39.188] Build status: STARTED
[11:30:39.189] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:10:0) after 9m54s
[11:40:33.243] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:10:0)
[11:40:33.271] Build status: STARTED
[11:40:33.272] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:11:0) after 7m3s
[11:47:36.324] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:11:0)
[11:47:36.345] Build status: STARTED
[11:47:36.345] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:12:0) after 8m51s
[11:56:27.423] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:12:0)
[11:56:27.439] Build status: STARTED
[11:56:27.439] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:13:0) after 2m27s
[11:58:54.518] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:13:0)
[11:58:54.554] Build status: STARTED
[11:58:54.554] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:14:0) after 2m12s
[12:01:06.637] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:14:0)
[12:01:06.659] Build status: STARTED
[12:01:06.659] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:15:0) after 8m6s
[12:09:12.673] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:15:0)
[12:09:12.729] Build status: STARTED
[12:09:12.729] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:16:0) after 3m34s
[12:12:46.745] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:16:0)
[12:12:46.794] Build status: STARTED
[12:12:46.794] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:17:0) after 3m57s
[12:16:43.817] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:17:0)
[12:16:44.016] Build status: STARTED
[12:16:44.016] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:18:0) after 7m50s
[12:24:34.031] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:18:0)
[12:24:34.050] Build status: STARTED
[12:24:34.050] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:19:0) after 5m27s
[12:30:01.070] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:19:0)
[12:30:01.120] Build status: STARTED
[12:30:01.120] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:20:0) after 2m23s
[12:32:24.124] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:20:0)
[12:32:24.143] Build status: STARTED
[12:32:24.143] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:21:0) after 9m1s
[12:41:25.162] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:21:0)
[12:41:25.189] Build status: STARTED
[12:41:25.189] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:22:0) after 3m33s
[12:44:58.204] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:22:0)
[12:44:58.242] Build status: STARTED
[12:44:58.242] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:23:0) after 9m17s
[12:54:15.307] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:23:0)
[12:54:15.326] Build status: STARTED
[12:54:15.326] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:24:0) after 6m9s
[13:00:24.417] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:24:0)
[13:00:24.460] Build status: STARTED
[13:00:24.460] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:25:0) after 1m47s
[13:02:11.475] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:25:0)
[13:02:11.495] Build status: STARTED
[13:02:11.495] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:26:0) after 1m33s
[13:03:44.465] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:26:0)
[13:03:44.485] Build status: STARTED
[13:03:44.485] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:27:0) after 8m41s
[13:12:25.532] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:27:0)
[13:12:25.610] Build status: STARTED
[13:12:25.610] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:28:0) after 6m2s
[13:18:27.609] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:28:0)
[13:18:27.628] Build status: STARTED
[13:18:27.628] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:29:0) after 1m33s
[13:20:00.644] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:29:0)
[13:20:00.664] Build status: STARTED
[13:20:00.664] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:30:0) after 5m31s
[13:25:31.714] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:30:0)
[13:25:31.731] Build status: STARTED
[13:25:31.731] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:31:0) after 6m7s
[13:31:38.747] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:31:0)
[13:31:38.769] Build status: STARTED
[13:31:38.769] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:32:0) after 1m47s
[13:33:25.797] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:32:0)
[13:33:25.823] Build status: STARTED
[13:33:25.823] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:33:0) after 7m10s
[13:40:35.836] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:33:0)
[13:40:35.850] Build status: STARTED
[13:40:35.850] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:34:0) after 4m23s
[13:44:58.866] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:34:0)
[13:44:58.906] Build status: STARTED
[13:44:58.906] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:35:0) after 9m15s
[13:54:13.878] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:35:0)
[13:54:14.048] Build status: STARTED
[13:54:14.048] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8866288116359519760:36:0) after 9m0s
[14:01:41.303] Received PubSub notification, asking Buildbucket for the build status
[14:01:41.320] Build:
{
"id": "8700858054254357761",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "mac-official"
},
"number": 134,
"createdBy": "project:chromium-m142",
"createTime": "2025-10-16T10:56:05.169611877Z",
"startTime": "2025-10-16T10:56:08.841296295Z",
"endTime": "2025-10-16T14:01:40.840839367Z",
"updateTime": "2025-10-16T14:01:40.840839367Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "29a72700e1d9a26624210ede90de17370ce1b503",
"ref": "refs/branch-heads/7444"
}
}
}
[14:01:41.320] Invocation finished in 3h5m37.54365489s with status SUCCEEDED