[18:29:02.228] New invocation is queued and will start shortly
[18:29:04.027] Starting the invocation (attempt 1)
[18:29:04.051] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7ef183a5efc56ffa8af456848279816dea1ce0bf"
[18:29:04.051] Popped gitiles commit info from properties and tags
[18:29:04.051] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:29:04.053] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:29:04.053] Buildbucket request:
{
"requestId": "8878399611194998384",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8878399611194998384",
"job": "chromium-m138/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6b8af4604950c0e11cc4b7b6708dfab41ea2789d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@6b8af4604950c0e11cc4b7b6708dfab41ea2789d",
"title": "6b8af4604950c0e11cc4b7b6708dfab41ea2789d",
"url": "https://chromium.googlesource.com/chromium/src/+/6b8af4604950c0e11cc4b7b6708dfab41ea2789d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bfa326d9a63eb5063a993daf3e89f118fd83ed3c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@bfa326d9a63eb5063a993daf3e89f118fd83ed3c",
"title": "bfa326d9a63eb5063a993daf3e89f118fd83ed3c",
"url": "https://chromium.googlesource.com/chromium/src/+/bfa326d9a63eb5063a993daf3e89f118fd83ed3c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f48b653464bf6a42c92497f02b693fb5c2b55022"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@f48b653464bf6a42c92497f02b693fb5c2b55022",
"title": "f48b653464bf6a42c92497f02b693fb5c2b55022",
"url": "https://chromium.googlesource.com/chromium/src/+/f48b653464bf6a42c92497f02b693fb5c2b55022"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0e0be047a89feecc43d2c760f44e078e283972f2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@0e0be047a89feecc43d2c760f44e078e283972f2",
"title": "0e0be047a89feecc43d2c760f44e078e283972f2",
"url": "https://chromium.googlesource.com/chromium/src/+/0e0be047a89feecc43d2c760f44e078e283972f2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c7d79a3961a9d1a41dd5925c036f5853149c4f4e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@c7d79a3961a9d1a41dd5925c036f5853149c4f4e",
"title": "c7d79a3961a9d1a41dd5925c036f5853149c4f4e",
"url": "https://chromium.googlesource.com/chromium/src/+/c7d79a3961a9d1a41dd5925c036f5853149c4f4e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "93cc2a508ec46d6d5d7e7735284996420a25e809"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@93cc2a508ec46d6d5d7e7735284996420a25e809",
"title": "93cc2a508ec46d6d5d7e7735284996420a25e809",
"url": "https://chromium.googlesource.com/chromium/src/+/93cc2a508ec46d6d5d7e7735284996420a25e809"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "157ea94621524e3785b812bc0c0c8ebfd6d8873f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@157ea94621524e3785b812bc0c0c8ebfd6d8873f",
"title": "157ea94621524e3785b812bc0c0c8ebfd6d8873f",
"url": "https://chromium.googlesource.com/chromium/src/+/157ea94621524e3785b812bc0c0c8ebfd6d8873f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "96c1997de1d824bd39cb4515de88fd023b68723c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@96c1997de1d824bd39cb4515de88fd023b68723c",
"title": "96c1997de1d824bd39cb4515de88fd023b68723c",
"url": "https://chromium.googlesource.com/chromium/src/+/96c1997de1d824bd39cb4515de88fd023b68723c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2529c3405e44d1281bfed83b8fdf562dd6ba8a27"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@2529c3405e44d1281bfed83b8fdf562dd6ba8a27",
"title": "2529c3405e44d1281bfed83b8fdf562dd6ba8a27",
"url": "https://chromium.googlesource.com/chromium/src/+/2529c3405e44d1281bfed83b8fdf562dd6ba8a27"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "743d6d4b1dc8149a6e74bee39a875c3149055d1e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@743d6d4b1dc8149a6e74bee39a875c3149055d1e",
"title": "743d6d4b1dc8149a6e74bee39a875c3149055d1e",
"url": "https://chromium.googlesource.com/chromium/src/+/743d6d4b1dc8149a6e74bee39a875c3149055d1e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7165ff4fa352bb2ddf729965a94378e10b69ed95"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@7165ff4fa352bb2ddf729965a94378e10b69ed95",
"title": "7165ff4fa352bb2ddf729965a94378e10b69ed95",
"url": "https://chromium.googlesource.com/chromium/src/+/7165ff4fa352bb2ddf729965a94378e10b69ed95"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7ef183a5efc56ffa8af456848279816dea1ce0bf"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@7ef183a5efc56ffa8af456848279816dea1ce0bf",
"title": "7ef183a5efc56ffa8af456848279816dea1ce0bf",
"url": "https://chromium.googlesource.com/chromium/src/+/7ef183a5efc56ffa8af456848279816dea1ce0bf"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "7ef183a5efc56ffa8af456848279816dea1ce0bf",
"ref": "refs/branch-heads/7204"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8878399611194998384"
},
{
"key": "scheduler_job_id",
"value": "chromium-m138/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:29:04.550] Scheduled build:
{
"id": "8712969548571039425",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "mac-official"
},
"number": 71,
"createdBy": "project:chromium-m138",
"createTime": "2025-06-04T18:29:04.106190367Z",
"updateTime": "2025-06-04T18:29:04.106190367Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "7ef183a5efc56ffa8af456848279816dea1ce0bf",
"ref": "refs/branch-heads/7204"
}
}
}
[18:29:04.550] Task URL: https://cr-buildbucket.appspot.com/build/8712969548571039425
[18:29:04.550] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:2:0) after 7m32s
[18:29:05.651] Received PubSub notification, asking Buildbucket for the build status
[18:29:05.668] Build status: SCHEDULED
[18:29:08.050] Received PubSub notification, asking Buildbucket for the build status
[18:29:08.095] Build status: STARTED
[18:36:36.575] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:2:0)
[18:36:37.317] Build status: STARTED
[18:36:37.318] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:5:0) after 4m0s
[18:40:37.390] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:5:0)
[18:40:37.418] Build status: STARTED
[18:40:37.418] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:6:0) after 2m24s
[18:43:01.384] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:6:0)
[18:43:01.413] Build status: STARTED
[18:43:01.413] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:7:0) after 3m36s
[18:46:37.523] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:7:0)
[18:46:37.546] Build status: STARTED
[18:46:37.546] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:8:0) after 2m40s
[18:49:17.625] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:8:0)
[18:49:17.643] Build status: STARTED
[18:49:17.643] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:9:0) after 1m27s
[18:50:44.640] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:9:0)
[18:50:44.665] Build status: STARTED
[18:50:44.665] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:10:0) after 5m47s
[18:56:31.691] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:10:0)
[18:56:31.720] Build status: STARTED
[18:56:31.720] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:11:0) after 8m57s
[19:05:28.779] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:11:0)
[19:05:28.818] Build status: STARTED
[19:05:28.818] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:12:0) after 2m59s
[19:08:27.797] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:12:0)
[19:08:27.816] Build status: STARTED
[19:08:27.816] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:13:0) after 8m46s
[19:17:13.888] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:13:0)
[19:17:13.972] Build status: STARTED
[19:17:13.972] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:14:0) after 2m28s
[19:19:42.084] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:14:0)
[19:19:42.101] Build status: STARTED
[19:19:42.101] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:15:0) after 7m28s
[19:27:10.204] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:15:0)
[19:27:10.232] Build status: STARTED
[19:27:10.232] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:16:0) after 3m0s
[19:30:10.356] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:16:0)
[19:30:10.412] Build status: STARTED
[19:30:10.412] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:17:0) after 7m7s
[19:37:17.432] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:17:0)
[19:37:17.455] Build status: STARTED
[19:37:17.455] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:18:0) after 1m49s
[19:39:06.595] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:18:0)
[19:39:06.618] Build status: STARTED
[19:39:06.618] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:19:0) after 9m8s
[19:48:14.643] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:19:0)
[19:48:14.663] Build status: STARTED
[19:48:14.663] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:20:0) after 8m31s
[19:56:45.683] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:20:0)
[19:56:45.707] Build status: STARTED
[19:56:45.707] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:21:0) after 7m53s
[20:04:38.833] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:21:0)
[20:04:38.848] Build status: STARTED
[20:04:38.848] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:22:0) after 7m58s
[20:12:36.933] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:22:0)
[20:12:36.953] Build status: STARTED
[20:12:36.953] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:23:0) after 2m54s
[20:15:31.026] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:23:0)
[20:15:31.046] Build status: STARTED
[20:15:31.046] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:24:0) after 9m14s
[20:24:45.068] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:24:0)
[20:24:45.116] Build status: STARTED
[20:24:45.116] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:25:0) after 9m4s
[20:33:49.144] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:25:0)
[20:33:49.162] Build status: STARTED
[20:33:49.162] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:26:0) after 6m0s
[20:39:49.222] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:26:0)
[20:39:49.243] Build status: STARTED
[20:39:49.244] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:27:0) after 1m40s
[20:41:29.260] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:27:0)
[20:41:29.452] Build status: STARTED
[20:41:29.452] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:28:0) after 6m9s
[20:47:38.471] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:28:0)
[20:47:38.489] Build status: STARTED
[20:47:38.489] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:29:0) after 2m47s
[20:50:25.516] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:29:0)
[20:50:25.578] Build status: STARTED
[20:50:25.578] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:30:0) after 2m40s
[20:53:05.649] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:30:0)
[20:53:05.669] Build status: STARTED
[20:53:05.669] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:31:0) after 5m55s
[20:59:00.838] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:31:0)
[20:59:00.857] Build status: STARTED
[20:59:00.857] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:32:0) after 8m24s
[21:07:24.878] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:32:0)
[21:07:24.981] Build status: STARTED
[21:07:24.981] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:33:0) after 6m41s
[21:14:05.961] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:33:0)
[21:14:05.995] Build status: STARTED
[21:14:05.995] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8878399611194998384:34:0) after 9m43s
[21:23:48.328] Received PubSub notification, asking Buildbucket for the build status
[21:23:48.354] Build:
{
"id": "8712969548571039425",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "mac-official"
},
"number": 71,
"createdBy": "project:chromium-m138",
"createTime": "2025-06-04T18:29:04.106190367Z",
"startTime": "2025-06-04T18:29:07.819809771Z",
"endTime": "2025-06-04T21:23:47.977704159Z",
"updateTime": "2025-06-04T21:23:47.977704159Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "7ef183a5efc56ffa8af456848279816dea1ce0bf",
"ref": "refs/branch-heads/7204"
}
}
}
[21:23:48.354] Invocation finished in 2h54m46.133804952s with status SUCCEEDED