[02:09:44.049] New invocation is queued and will start shortly
[02:09:45.131] Starting the invocation (attempt 1)
[02:09:45.156] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f55c314c84ca6e3c6b73ecaca850ffdcce85c070"
[02:09:45.157] Popped gitiles commit info from properties and tags
[02:09:45.157] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:09:45.157] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:09:45.157] Buildbucket request:
{
"requestId": "8883444056758921488",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8883444056758921488",
"job": "chromium-m134/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "85b94951afe3a988658f7f4d31cbdf0e98b6300c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@85b94951afe3a988658f7f4d31cbdf0e98b6300c",
"title": "85b94951afe3a988658f7f4d31cbdf0e98b6300c",
"url": "https://chromium.googlesource.com/chromium/src/+/85b94951afe3a988658f7f4d31cbdf0e98b6300c"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2bb8848b71dfd200882b354c908dfcc6af101e71"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@2bb8848b71dfd200882b354c908dfcc6af101e71",
"title": "2bb8848b71dfd200882b354c908dfcc6af101e71",
"url": "https://chromium.googlesource.com/chromium/src/+/2bb8848b71dfd200882b354c908dfcc6af101e71"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ff433c444a2ea862f5510b3f22ba535963a65ef9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@ff433c444a2ea862f5510b3f22ba535963a65ef9",
"title": "ff433c444a2ea862f5510b3f22ba535963a65ef9",
"url": "https://chromium.googlesource.com/chromium/src/+/ff433c444a2ea862f5510b3f22ba535963a65ef9"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "62ac10b62e3b9f58056074fa9814e3de07e7cc14"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@62ac10b62e3b9f58056074fa9814e3de07e7cc14",
"title": "62ac10b62e3b9f58056074fa9814e3de07e7cc14",
"url": "https://chromium.googlesource.com/chromium/src/+/62ac10b62e3b9f58056074fa9814e3de07e7cc14"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0417ac97d74826259fc651d3201de01c8fdc0751"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@0417ac97d74826259fc651d3201de01c8fdc0751",
"title": "0417ac97d74826259fc651d3201de01c8fdc0751",
"url": "https://chromium.googlesource.com/chromium/src/+/0417ac97d74826259fc651d3201de01c8fdc0751"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "288d413c2bcdf4db8551a9975c375f7ba850e393"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@288d413c2bcdf4db8551a9975c375f7ba850e393",
"title": "288d413c2bcdf4db8551a9975c375f7ba850e393",
"url": "https://chromium.googlesource.com/chromium/src/+/288d413c2bcdf4db8551a9975c375f7ba850e393"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f55c314c84ca6e3c6b73ecaca850ffdcce85c070"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@f55c314c84ca6e3c6b73ecaca850ffdcce85c070",
"title": "f55c314c84ca6e3c6b73ecaca850ffdcce85c070",
"url": "https://chromium.googlesource.com/chromium/src/+/f55c314c84ca6e3c6b73ecaca850ffdcce85c070"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f55c314c84ca6e3c6b73ecaca850ffdcce85c070",
"ref": "refs/branch-heads/6998"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8883444056758921488"
},
{
"key": "scheduler_job_id",
"value": "chromium-m134/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:09:45.689] Scheduled build:
{
"id": "8718013994886817553",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "mac-official"
},
"number": 474,
"createdBy": "project:chromium-m134",
"createTime": "2025-04-10T02:09:45.214508390Z",
"updateTime": "2025-04-10T02:09:45.214508390Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f55c314c84ca6e3c6b73ecaca850ffdcce85c070",
"ref": "refs/branch-heads/6998"
}
}
}
[02:09:45.689] Task URL: https://cr-buildbucket.appspot.com/build/8718013994886817553
[02:09:45.689] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:2:0) after 4m20s
[02:09:46.686] Received PubSub notification, asking Buildbucket for the build status
[02:09:46.709] Build status: SCHEDULED
[02:14:05.714] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:2:0)
[02:14:05.792] Build status: SCHEDULED
[02:14:05.792] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:4:0) after 4m26s
[02:17:07.505] Received PubSub notification, asking Buildbucket for the build status
[02:17:07.532] Build status: STARTED
[02:18:32.046] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:4:0)
[02:18:32.116] Build status: STARTED
[02:18:32.116] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:6:0) after 1m38s
[02:20:10.132] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:6:0)
[02:20:10.151] Build status: STARTED
[02:20:10.151] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:7:0) after 7m24s
[02:27:34.350] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:7:0)
[02:27:34.405] Build status: STARTED
[02:27:34.405] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:8:0) after 4m10s
[02:31:44.424] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:8:0)
[02:31:44.446] Build status: STARTED
[02:31:44.446] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:9:0) after 8m8s
[02:39:52.495] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:9:0)
[02:39:52.593] Build status: STARTED
[02:39:52.593] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:10:0) after 1m11s
[02:41:03.808] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:10:0)
[02:41:03.833] Build status: STARTED
[02:41:03.833] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:11:0) after 5m11s
[02:46:14.882] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:11:0)
[02:46:14.912] Build status: STARTED
[02:46:14.912] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:12:0) after 1m37s
[02:47:51.935] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:12:0)
[02:47:51.962] Build status: STARTED
[02:47:51.962] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:13:0) after 1m37s
[02:49:28.983] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:13:0)
[02:49:29.203] Build status: STARTED
[02:49:29.203] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:14:0) after 5m0s
[02:54:29.270] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:14:0)
[02:54:29.306] Build status: STARTED
[02:54:29.306] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:15:0) after 2m1s
[02:56:30.379] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:15:0)
[02:56:30.398] Build status: STARTED
[02:56:30.398] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:16:0) after 1m12s
[02:57:42.421] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:16:0)
[02:57:42.448] Build status: STARTED
[02:57:42.448] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:17:0) after 4m6s
[03:01:48.466] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:17:0)
[03:01:48.524] Build status: STARTED
[03:01:48.524] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:18:0) after 1m46s
[03:03:34.546] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:18:0)
[03:03:34.616] Build status: STARTED
[03:03:34.616] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:19:0) after 3m17s
[03:06:51.638] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:19:0)
[03:06:51.655] Build status: STARTED
[03:06:51.655] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:20:0) after 7m9s
[03:14:00.829] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:20:0)
[03:14:00.861] Build status: STARTED
[03:14:00.861] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:21:0) after 6m0s
[03:20:00.883] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:21:0)
[03:20:00.900] Build status: STARTED
[03:20:00.901] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:22:0) after 8m26s
[03:28:26.918] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:22:0)
[03:28:26.937] Build status: STARTED
[03:28:26.937] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:23:0) after 1m22s
[03:29:48.960] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:23:0)
[03:29:48.985] Build status: STARTED
[03:29:48.985] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:24:0) after 7m57s
[03:37:46.050] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:24:0)
[03:37:46.077] Build status: STARTED
[03:37:46.077] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:25:0) after 8m1s
[03:45:47.250] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:25:0)
[03:45:47.267] Build status: STARTED
[03:45:47.267] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:26:0) after 1m12s
[03:46:59.537] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:26:0)
[03:46:59.561] Build status: STARTED
[03:46:59.561] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:27:0) after 2m59s
[03:49:58.556] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:27:0)
[03:49:58.572] Build status: STARTED
[03:49:58.572] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:28:0) after 6m47s
[03:56:45.588] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:28:0)
[03:56:45.607] Build status: STARTED
[03:56:45.607] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:29:0) after 7m18s
[04:04:04.374] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:29:0)
[04:04:04.397] Build status: STARTED
[04:04:04.397] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:30:0) after 8m7s
[04:12:11.421] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:30:0)
[04:12:11.439] Build status: STARTED
[04:12:11.439] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:31:0) after 2m22s
[04:14:33.465] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:31:0)
[04:14:33.486] Build status: STARTED
[04:14:33.486] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:32:0) after 7m29s
[04:22:02.551] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:32:0)
[04:22:02.569] Build status: STARTED
[04:22:02.569] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:33:0) after 5m34s
[04:27:36.636] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:33:0)
[04:27:36.653] Build status: STARTED
[04:27:36.653] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:34:0) after 3m49s
[04:31:25.719] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:34:0)
[04:31:25.735] Build status: STARTED
[04:31:25.735] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:35:0) after 3m0s
[04:34:25.758] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:35:0)
[04:34:25.819] Build status: STARTED
[04:34:25.819] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:36:0) after 1m24s
[04:35:49.899] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:36:0)
[04:35:49.918] Build status: STARTED
[04:35:49.918] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:37:0) after 5m35s
[04:41:25.024] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:37:0)
[04:41:25.041] Build status: STARTED
[04:41:25.041] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:38:0) after 1m46s
[04:43:11.072] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:38:0)
[04:43:11.100] Build status: STARTED
[04:43:11.100] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:39:0) after 4m34s
[04:47:45.115] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:39:0)
[04:47:45.132] Build status: STARTED
[04:47:45.132] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:40:0) after 9m36s
[04:57:21.261] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:40:0)
[04:57:21.280] Build status: STARTED
[04:57:21.280] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:41:0) after 7m3s
[05:04:24.353] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:41:0)
[05:04:24.372] Build status: STARTED
[05:04:24.372] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:42:0) after 3m13s
[05:07:37.391] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:42:0)
[05:07:37.418] Build status: STARTED
[05:07:37.418] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:43:0) after 7m17s
[05:14:54.441] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:43:0)
[05:14:54.502] Build status: STARTED
[05:14:54.502] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:44:0) after 8m21s
[05:23:15.532] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:44:0)
[05:23:15.546] Build status: STARTED
[05:23:15.546] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:45:0) after 7m54s
[05:31:10.248] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:45:0)
[05:31:10.265] Build status: STARTED
[05:31:10.265] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8883444056758921488:46:0) after 5m8s
[05:35:33.660] Received PubSub notification, asking Buildbucket for the build status
[05:35:33.714] Build:
{
"id": "8718013994886817553",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "mac-official"
},
"number": 474,
"createdBy": "project:chromium-m134",
"createTime": "2025-04-10T02:09:45.214508390Z",
"startTime": "2025-04-10T02:16:58.183002379Z",
"endTime": "2025-04-10T05:35:32.994155740Z",
"updateTime": "2025-04-10T05:35:32.994155740Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f55c314c84ca6e3c6b73ecaca850ffdcce85c070",
"ref": "refs/branch-heads/6998"
}
}
}
[05:35:33.714] Invocation finished in 3h25m49.668964152s with status SUCCEEDED