[21:13:47.273] New invocation is queued and will start shortly
[21:13:48.410] Starting the invocation (attempt 1)
[21:13:48.433] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a577e123ff69a0516f1172a75c99584749cc48b0"
[21:13:48.433] Popped gitiles commit info from properties and tags
[21:13:48.433] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:13:48.433] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:13:48.433] Buildbucket request:
{
"requestId": "8859363883027339232",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8859363883027339232",
"job": "chromium-m144/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8b803d882f27bbb3641252f26fd50f15046962f7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@8b803d882f27bbb3641252f26fd50f15046962f7",
"title": "8b803d882f27bbb3641252f26fd50f15046962f7",
"url": "https://chromium.googlesource.com/chromium/src/+/8b803d882f27bbb3641252f26fd50f15046962f7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3a3fc499b80e2beff150cc9974b663e2e6d4b1e4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@3a3fc499b80e2beff150cc9974b663e2e6d4b1e4",
"title": "3a3fc499b80e2beff150cc9974b663e2e6d4b1e4",
"url": "https://chromium.googlesource.com/chromium/src/+/3a3fc499b80e2beff150cc9974b663e2e6d4b1e4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "37b1a3c6293bc99c73da31ad1cf63d5bc7a7cd74"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@37b1a3c6293bc99c73da31ad1cf63d5bc7a7cd74",
"title": "37b1a3c6293bc99c73da31ad1cf63d5bc7a7cd74",
"url": "https://chromium.googlesource.com/chromium/src/+/37b1a3c6293bc99c73da31ad1cf63d5bc7a7cd74"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6239953079c1c92253ae86bb493e6670161e66eb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@6239953079c1c92253ae86bb493e6670161e66eb",
"title": "6239953079c1c92253ae86bb493e6670161e66eb",
"url": "https://chromium.googlesource.com/chromium/src/+/6239953079c1c92253ae86bb493e6670161e66eb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9ad1f601938400ff046920027c452418cbe9e3d4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@9ad1f601938400ff046920027c452418cbe9e3d4",
"title": "9ad1f601938400ff046920027c452418cbe9e3d4",
"url": "https://chromium.googlesource.com/chromium/src/+/9ad1f601938400ff046920027c452418cbe9e3d4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "665bbdece1aab4e1a6429cd6d5fb570a363c91a7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@665bbdece1aab4e1a6429cd6d5fb570a363c91a7",
"title": "665bbdece1aab4e1a6429cd6d5fb570a363c91a7",
"url": "https://chromium.googlesource.com/chromium/src/+/665bbdece1aab4e1a6429cd6d5fb570a363c91a7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4332dc50c4df6b499443d8b8bc556f6fa9c368cc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@4332dc50c4df6b499443d8b8bc556f6fa9c368cc",
"title": "4332dc50c4df6b499443d8b8bc556f6fa9c368cc",
"url": "https://chromium.googlesource.com/chromium/src/+/4332dc50c4df6b499443d8b8bc556f6fa9c368cc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a577e123ff69a0516f1172a75c99584749cc48b0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@a577e123ff69a0516f1172a75c99584749cc48b0",
"title": "a577e123ff69a0516f1172a75c99584749cc48b0",
"url": "https://chromium.googlesource.com/chromium/src/+/a577e123ff69a0516f1172a75c99584749cc48b0"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a577e123ff69a0516f1172a75c99584749cc48b0",
"ref": "refs/branch-heads/7559"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8859363883027339232"
},
{
"key": "scheduler_job_id",
"value": "chromium-m144/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:13:48.868] Scheduled build:
{
"id": "8693933821111478289",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "mac-official"
},
"number": 198,
"createdBy": "project:chromium-m144",
"createTime": "2025-12-31T21:13:48.478605945Z",
"updateTime": "2025-12-31T21:13:48.478605945Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a577e123ff69a0516f1172a75c99584749cc48b0",
"ref": "refs/branch-heads/7559"
}
}
}
[21:13:48.868] Task URL: https://cr-buildbucket.appspot.com/build/8693933821111478289
[21:13:48.868] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:2:0) after 6m10s
[21:13:48.921] Received PubSub notification, asking Buildbucket for the build status
[21:13:48.933] Build status: SCHEDULED
[21:13:52.834] Received PubSub notification, asking Buildbucket for the build status
[21:13:53.083] Build status: STARTED
[21:19:58.882] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:2:0)
[21:19:58.898] Build status: STARTED
[21:19:58.898] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:5:0) after 8m21s
[21:28:19.911] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:5:0)
[21:28:19.925] Build status: STARTED
[21:28:19.925] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:6:0) after 3m44s
[21:32:03.945] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:6:0)
[21:32:03.957] Build status: STARTED
[21:32:03.957] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:7:0) after 6m1s
[21:38:04.971] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:7:0)
[21:38:04.987] Build status: STARTED
[21:38:04.987] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:8:0) after 8m46s
[21:46:51.383] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:8:0)
[21:46:51.408] Build status: STARTED
[21:46:51.408] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:9:0) after 7m26s
[21:54:17.419] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:9:0)
[21:54:17.472] Build status: STARTED
[21:54:17.472] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:10:0) after 8m41s
[22:02:58.483] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:10:0)
[22:02:58.504] Build status: STARTED
[22:02:58.504] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:11:0) after 8m38s
[22:11:36.586] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:11:0)
[22:11:36.598] Build status: STARTED
[22:11:36.599] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:12:0) after 8m1s
[22:19:37.705] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:12:0)
[22:19:37.718] Build status: STARTED
[22:19:37.719] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:13:0) after 1m28s
[22:21:05.843] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:13:0)
[22:21:05.873] Build status: STARTED
[22:21:05.874] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:14:0) after 4m45s
[22:25:50.901] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:14:0)
[22:25:50.968] Build status: STARTED
[22:25:50.968] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:15:0) after 1m41s
[22:27:32.182] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:15:0)
[22:27:32.252] Build status: STARTED
[22:27:32.252] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:16:0) after 2m29s
[22:30:01.322] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:16:0)
[22:30:01.348] Build status: STARTED
[22:30:01.348] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:17:0) after 1m53s
[22:31:54.479] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:17:0)
[22:31:54.561] Build status: STARTED
[22:31:54.562] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:18:0) after 5m18s
[22:37:12.573] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:18:0)
[22:37:12.600] Build status: STARTED
[22:37:12.600] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:19:0) after 6m46s
[22:43:58.612] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:19:0)
[22:43:58.632] Build status: STARTED
[22:43:58.632] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:20:0) after 5m23s
[22:49:21.645] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:20:0)
[22:49:21.658] Build status: STARTED
[22:49:21.658] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:21:0) after 1m57s
[22:51:18.669] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:21:0)
[22:51:18.686] Build status: STARTED
[22:51:18.686] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:22:0) after 9m32s
[23:00:50.849] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:22:0)
[23:00:50.863] Build status: STARTED
[23:00:50.863] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:23:0) after 6m56s
[23:07:46.878] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:23:0)
[23:07:46.900] Build status: STARTED
[23:07:46.900] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:24:0) after 5m0s
[23:12:46.876] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:24:0)
[23:12:46.911] Build status: STARTED
[23:12:46.911] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:25:0) after 7m59s
[23:20:45.927] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:25:0)
[23:20:45.940] Build status: STARTED
[23:20:45.940] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:26:0) after 5m55s
[23:26:41.055] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:26:0)
[23:26:41.107] Build status: STARTED
[23:26:41.107] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:27:0) after 3m5s
[23:29:46.123] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:27:0)
[23:29:46.135] Build status: STARTED
[23:29:46.135] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:28:0) after 1m23s
[23:31:09.149] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:28:0)
[23:31:09.234] Build status: STARTED
[23:31:09.234] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:29:0) after 9m16s
[23:40:25.279] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:29:0)
[23:40:25.313] Build status: STARTED
[23:40:25.313] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:30:0) after 7m15s
[23:47:40.383] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:30:0)
[23:47:40.403] Build status: STARTED
[23:47:40.403] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:31:0) after 2m21s
[23:50:01.518] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:31:0)
[23:50:01.530] Build status: STARTED
[23:50:01.530] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:32:0) after 3m13s
[23:53:14.508] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:32:0)
[23:53:14.587] Build status: STARTED
[23:53:14.587] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:33:0) after 2m6s
[23:55:20.608] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:33:0)
[23:55:20.690] Build status: STARTED
[23:55:20.691] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:34:0) after 4m13s
[23:59:33.705] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:34:0)
[23:59:33.719] Build status: STARTED
[23:59:33.720] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:35:0) after 7m28s
[00:07:01.836] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:35:0)
[00:07:01.855] Build status: STARTED
[00:07:01.855] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:36:0) after 6m46s
[00:13:47.868] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:36:0)
[00:13:47.885] Build status: STARTED
[00:13:47.885] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:37:0) after 5m30s
[00:19:17.976] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:37:0)
[00:19:17.989] Build status: STARTED
[00:19:17.989] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:38:0) after 8m40s
[00:27:58.032] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:38:0)
[00:27:58.047] Build status: STARTED
[00:27:58.047] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:39:0) after 8m41s
[00:36:39.097] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:39:0)
[00:36:39.115] Build status: STARTED
[00:36:39.115] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859363883027339232:40:0) after 5m12s
[00:40:36.244] Received PubSub notification, asking Buildbucket for the build status
[00:40:36.258] Build:
{
"id": "8693933821111478289",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "mac-official"
},
"number": 198,
"createdBy": "project:chromium-m144",
"createTime": "2025-12-31T21:13:48.478605945Z",
"startTime": "2025-12-31T21:13:52.697165210Z",
"endTime": "2026-01-01T00:40:36.027233782Z",
"updateTime": "2026-01-01T00:40:36.027233782Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a577e123ff69a0516f1172a75c99584749cc48b0",
"ref": "refs/branch-heads/7559"
}
}
}
[00:40:36.258] Invocation finished in 3h26m48.991253111s with status SUCCEEDED