[08:41:40.286] New invocation is queued and will start shortly
[08:41:41.402] Starting the invocation (attempt 1)
[08:41:41.439] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a689ec66f61f7afd8ac169e73f7b4fab909afba8"
[08:41:41.439] Popped gitiles commit info from properties and tags
[08:41:41.439] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:41:41.442] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:41:41.442] Buildbucket request:
{
"requestId": "8873816119759815856",
"builder": {
"project": "chromium-m139",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8873816119759815856",
"job": "chromium-m139/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7f148c2b9246c9d9fa50b76d0e554929fc2079e1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@7f148c2b9246c9d9fa50b76d0e554929fc2079e1",
"title": "7f148c2b9246c9d9fa50b76d0e554929fc2079e1",
"url": "https://chromium.googlesource.com/chromium/src/+/7f148c2b9246c9d9fa50b76d0e554929fc2079e1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "abb0c5339ad22d7c8f88c9acc5ea8594e25555dc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@abb0c5339ad22d7c8f88c9acc5ea8594e25555dc",
"title": "abb0c5339ad22d7c8f88c9acc5ea8594e25555dc",
"url": "https://chromium.googlesource.com/chromium/src/+/abb0c5339ad22d7c8f88c9acc5ea8594e25555dc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "eb450d0f1dbd95a1cc4632c2879df06850c05672"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@eb450d0f1dbd95a1cc4632c2879df06850c05672",
"title": "eb450d0f1dbd95a1cc4632c2879df06850c05672",
"url": "https://chromium.googlesource.com/chromium/src/+/eb450d0f1dbd95a1cc4632c2879df06850c05672"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "00619123c5e47dcea49221859bda94952eccdb21"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@00619123c5e47dcea49221859bda94952eccdb21",
"title": "00619123c5e47dcea49221859bda94952eccdb21",
"url": "https://chromium.googlesource.com/chromium/src/+/00619123c5e47dcea49221859bda94952eccdb21"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c5e672994ce393c110bccde6917803e0d5e67107"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@c5e672994ce393c110bccde6917803e0d5e67107",
"title": "c5e672994ce393c110bccde6917803e0d5e67107",
"url": "https://chromium.googlesource.com/chromium/src/+/c5e672994ce393c110bccde6917803e0d5e67107"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "507c7c7c13eee0a5896b01e1b08b367999ae7a45"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@507c7c7c13eee0a5896b01e1b08b367999ae7a45",
"title": "507c7c7c13eee0a5896b01e1b08b367999ae7a45",
"url": "https://chromium.googlesource.com/chromium/src/+/507c7c7c13eee0a5896b01e1b08b367999ae7a45"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a689ec66f61f7afd8ac169e73f7b4fab909afba8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@a689ec66f61f7afd8ac169e73f7b4fab909afba8",
"title": "a689ec66f61f7afd8ac169e73f7b4fab909afba8",
"url": "https://chromium.googlesource.com/chromium/src/+/a689ec66f61f7afd8ac169e73f7b4fab909afba8"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a689ec66f61f7afd8ac169e73f7b4fab909afba8",
"ref": "refs/branch-heads/7258"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8873816119759815856"
},
{
"key": "scheduler_job_id",
"value": "chromium-m139/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[08:41:42.392] Scheduled build:
{
"id": "8708386057821861825",
"builder": {
"project": "chromium-m139",
"bucket": "ci",
"builder": "mac-official"
},
"number": 258,
"createdBy": "project:chromium-m139",
"createTime": "2025-07-25T08:41:41.513206956Z",
"updateTime": "2025-07-25T08:41:41.513206956Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a689ec66f61f7afd8ac169e73f7b4fab909afba8",
"ref": "refs/branch-heads/7258"
}
}
}
[08:41:42.392] Task URL: https://cr-buildbucket.appspot.com/build/8708386057821861825
[08:41:42.392] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:2:0) after 9m39s
[08:41:43.133] Received PubSub notification, asking Buildbucket for the build status
[08:41:43.208] Build status: SCHEDULED
[08:41:48.001] Received PubSub notification, asking Buildbucket for the build status
[08:41:48.049] Build status: STARTED
[08:51:21.456] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:2:0)
[08:51:21.488] Build status: STARTED
[08:51:21.488] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:5:0) after 2m57s
[08:54:18.547] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:5:0)
[08:54:18.576] Build status: STARTED
[08:54:18.576] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:6:0) after 5m33s
[08:59:51.664] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:6:0)
[08:59:51.694] Build status: STARTED
[08:59:51.694] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:7:0) after 8m34s
[09:08:25.721] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:7:0)
[09:08:25.757] Build status: STARTED
[09:08:25.757] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:8:0) after 9m37s
[09:18:02.779] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:8:0)
[09:18:02.904] Build status: STARTED
[09:18:02.904] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:9:0) after 2m26s
[09:20:28.914] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:9:0)
[09:20:28.934] Build status: STARTED
[09:20:28.934] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:10:0) after 9m35s
[09:30:03.960] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:10:0)
[09:30:04.078] Build status: STARTED
[09:30:04.078] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:11:0) after 3m26s
[09:33:30.079] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:11:0)
[09:33:30.120] Build status: STARTED
[09:33:30.120] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:12:0) after 5m23s
[09:38:53.185] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:12:0)
[09:38:53.208] Build status: STARTED
[09:38:53.208] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:13:0) after 3m58s
[09:42:51.283] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:13:0)
[09:42:51.304] Build status: STARTED
[09:42:51.304] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:14:0) after 2m5s
[09:44:56.349] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:14:0)
[09:44:56.372] Build status: STARTED
[09:44:56.372] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:15:0) after 5m37s
[09:50:33.406] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:15:0)
[09:50:33.425] Build status: STARTED
[09:50:33.425] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:16:0) after 5m34s
[09:56:07.545] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:16:0)
[09:56:07.564] Build status: STARTED
[09:56:07.564] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:17:0) after 3m45s
[09:59:52.639] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:17:0)
[09:59:52.723] Build status: STARTED
[09:59:52.724] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:18:0) after 9m55s
[10:09:47.796] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:18:0)
[10:09:47.824] Build status: STARTED
[10:09:47.824] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:19:0) after 1m5s
[10:10:52.849] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:19:0)
[10:10:52.874] Build status: STARTED
[10:10:52.874] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:20:0) after 3m20s
[10:14:13.055] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:20:0)
[10:14:13.082] Build status: STARTED
[10:14:13.082] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:21:0) after 8m53s
[10:23:06.064] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:21:0)
[10:23:06.096] Build status: STARTED
[10:23:06.096] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:22:0) after 5m38s
[10:28:44.301] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:22:0)
[10:28:44.338] Build status: STARTED
[10:28:44.338] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:23:0) after 6m32s
[10:35:16.602] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:23:0)
[10:35:16.627] Build status: STARTED
[10:35:16.627] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:24:0) after 8m33s
[10:43:49.676] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:24:0)
[10:43:49.698] Build status: STARTED
[10:43:49.698] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:25:0) after 5m52s
[10:49:41.876] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:25:0)
[10:49:41.896] Build status: STARTED
[10:49:41.896] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:26:0) after 7m13s
[10:56:54.917] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:26:0)
[10:56:54.982] Build status: STARTED
[10:56:54.982] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:27:0) after 2m28s
[10:59:23.003] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:27:0)
[10:59:23.022] Build status: STARTED
[10:59:23.022] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:28:0) after 2m11s
[11:01:34.057] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:28:0)
[11:01:34.076] Build status: STARTED
[11:01:34.076] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:29:0) after 4m4s
[11:05:38.106] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:29:0)
[11:05:38.133] Build status: STARTED
[11:05:38.133] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:30:0) after 3m21s
[11:08:59.211] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:30:0)
[11:08:59.269] Build status: STARTED
[11:08:59.269] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:31:0) after 2m19s
[11:11:18.287] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:31:0)
[11:11:18.307] Build status: STARTED
[11:11:18.307] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:32:0) after 6m22s
[11:17:40.386] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:32:0)
[11:17:40.433] Build status: STARTED
[11:17:40.433] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:33:0) after 2m59s
[11:20:39.520] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:33:0)
[11:20:39.545] Build status: STARTED
[11:20:39.546] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:34:0) after 8m22s
[11:29:01.600] Handling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:34:0)
[11:29:01.631] Build status: STARTED
[11:29:01.631] Scheduling timer "check-buildbucket-build-status" (chromium-m139/mac-official:8873816119759815856:35:0) after 7m35s
[11:34:08.089] Received PubSub notification, asking Buildbucket for the build status
[11:34:08.109] Build:
{
"id": "8708386057821861825",
"builder": {
"project": "chromium-m139",
"bucket": "ci",
"builder": "mac-official"
},
"number": 258,
"createdBy": "project:chromium-m139",
"createTime": "2025-07-25T08:41:41.513206956Z",
"startTime": "2025-07-25T08:41:47.749735521Z",
"endTime": "2025-07-25T11:33:39.944149094Z",
"updateTime": "2025-07-25T11:33:39.944149094Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a689ec66f61f7afd8ac169e73f7b4fab909afba8",
"ref": "refs/branch-heads/7258"
}
}
}
[11:34:08.109] Invocation finished in 2h52m27.828064585s with status SUCCEEDED