[01:40:47.447] New invocation is queued and will start shortly
[01:40:48.728] Starting the invocation (attempt 1)
[01:40:48.752] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/44e6a5074e562acf4bfb4b4881298738cc3bd2e7"
[01:40:48.752] Popped gitiles commit info from properties and tags
[01:40:48.752] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:40:48.753] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:40:48.753] Buildbucket request:
{
"requestId": "8890421844139070480",
"builder": {
"project": "chromium-m133",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8890421844139070480",
"job": "chromium-m133/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "11239b05ce660a5c002f8f9d9286aabd5278e707"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@11239b05ce660a5c002f8f9d9286aabd5278e707",
"title": "11239b05ce660a5c002f8f9d9286aabd5278e707",
"url": "https://chromium.googlesource.com/chromium/src/+/11239b05ce660a5c002f8f9d9286aabd5278e707"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8f0309bef095757a7ec4d3b62485f6d14fd7c283"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@8f0309bef095757a7ec4d3b62485f6d14fd7c283",
"title": "8f0309bef095757a7ec4d3b62485f6d14fd7c283",
"url": "https://chromium.googlesource.com/chromium/src/+/8f0309bef095757a7ec4d3b62485f6d14fd7c283"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a7d7f1c5d1a5add1e7cdbe6359600447dea76c99"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@a7d7f1c5d1a5add1e7cdbe6359600447dea76c99",
"title": "a7d7f1c5d1a5add1e7cdbe6359600447dea76c99",
"url": "https://chromium.googlesource.com/chromium/src/+/a7d7f1c5d1a5add1e7cdbe6359600447dea76c99"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0bd0d9a1974a5b5de5f0cef83514ce3154c6cc9c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@0bd0d9a1974a5b5de5f0cef83514ce3154c6cc9c",
"title": "0bd0d9a1974a5b5de5f0cef83514ce3154c6cc9c",
"url": "https://chromium.googlesource.com/chromium/src/+/0bd0d9a1974a5b5de5f0cef83514ce3154c6cc9c"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "aa14d6207a5001ca6a179f97c05bba10cfbd390f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@aa14d6207a5001ca6a179f97c05bba10cfbd390f",
"title": "aa14d6207a5001ca6a179f97c05bba10cfbd390f",
"url": "https://chromium.googlesource.com/chromium/src/+/aa14d6207a5001ca6a179f97c05bba10cfbd390f"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0f2f48e262a83c4eb32113a6d5c97c95127ac26e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@0f2f48e262a83c4eb32113a6d5c97c95127ac26e",
"title": "0f2f48e262a83c4eb32113a6d5c97c95127ac26e",
"url": "https://chromium.googlesource.com/chromium/src/+/0f2f48e262a83c4eb32113a6d5c97c95127ac26e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "647038ebddea6045017c79d39eb5f35b8a515828"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@647038ebddea6045017c79d39eb5f35b8a515828",
"title": "647038ebddea6045017c79d39eb5f35b8a515828",
"url": "https://chromium.googlesource.com/chromium/src/+/647038ebddea6045017c79d39eb5f35b8a515828"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9cb9ced5a014681e9910f353d0364c79da1fa6af"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@9cb9ced5a014681e9910f353d0364c79da1fa6af",
"title": "9cb9ced5a014681e9910f353d0364c79da1fa6af",
"url": "https://chromium.googlesource.com/chromium/src/+/9cb9ced5a014681e9910f353d0364c79da1fa6af"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "35f8dd1b94fdcdb4febabfbb92713ce4883b115d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@35f8dd1b94fdcdb4febabfbb92713ce4883b115d",
"title": "35f8dd1b94fdcdb4febabfbb92713ce4883b115d",
"url": "https://chromium.googlesource.com/chromium/src/+/35f8dd1b94fdcdb4febabfbb92713ce4883b115d"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9520333b8ba684c4dd7161133690538d4e03ed1d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@9520333b8ba684c4dd7161133690538d4e03ed1d",
"title": "9520333b8ba684c4dd7161133690538d4e03ed1d",
"url": "https://chromium.googlesource.com/chromium/src/+/9520333b8ba684c4dd7161133690538d4e03ed1d"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "44e6a5074e562acf4bfb4b4881298738cc3bd2e7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@44e6a5074e562acf4bfb4b4881298738cc3bd2e7",
"title": "44e6a5074e562acf4bfb4b4881298738cc3bd2e7",
"url": "https://chromium.googlesource.com/chromium/src/+/44e6a5074e562acf4bfb4b4881298738cc3bd2e7"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "44e6a5074e562acf4bfb4b4881298738cc3bd2e7",
"ref": "refs/branch-heads/6943"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8890421844139070480"
},
{
"key": "scheduler_job_id",
"value": "chromium-m133/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:40:49.488] Scheduled build:
{
"id": "8724991782047625489",
"builder": {
"project": "chromium-m133",
"bucket": "ci",
"builder": "mac-official"
},
"number": 129,
"createdBy": "project:chromium-m133",
"createTime": "2025-01-23T01:40:48.813189209Z",
"updateTime": "2025-01-23T01:40:48.813189209Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "44e6a5074e562acf4bfb4b4881298738cc3bd2e7",
"ref": "refs/branch-heads/6943"
}
}
}
[01:40:49.488] Task URL: https://cr-buildbucket.appspot.com/build/8724991782047625489
[01:40:49.488] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:2:0) after 9m40s
[01:41:12.541] Received PubSub notification, asking Buildbucket for the build status
[01:41:12.585] Build status: STARTED
[01:41:16.818] Received PubSub notification, asking Buildbucket for the build status
[01:41:16.847] Build status: STARTED
[01:50:29.510] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:2:0)
[01:50:29.538] Build status: STARTED
[01:50:29.538] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:5:0) after 4m37s
[01:55:06.565] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:5:0)
[01:55:06.593] Build status: STARTED
[01:55:06.594] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:6:0) after 2m59s
[01:58:05.617] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:6:0)
[01:58:05.639] Build status: STARTED
[01:58:05.639] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:7:0) after 1m9s
[01:59:14.773] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:7:0)
[01:59:14.801] Build status: STARTED
[01:59:14.801] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:8:0) after 1m24s
[02:00:38.823] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:8:0)
[02:00:38.844] Build status: STARTED
[02:00:38.844] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:9:0) after 8m14s
[02:08:52.850] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:9:0)
[02:08:52.876] Build status: STARTED
[02:08:52.876] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:10:0) after 5m0s
[02:13:52.897] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:10:0)
[02:13:52.921] Build status: STARTED
[02:13:52.921] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:11:0) after 7m57s
[02:21:49.945] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:11:0)
[02:21:49.983] Build status: STARTED
[02:21:49.983] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:12:0) after 1m20s
[02:23:10.005] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:12:0)
[02:23:10.021] Build status: STARTED
[02:23:10.021] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:13:0) after 7m7s
[02:30:17.100] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:13:0)
[02:30:17.119] Build status: STARTED
[02:30:17.119] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:14:0) after 4m6s
[02:34:23.345] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:14:0)
[02:34:23.377] Build status: STARTED
[02:34:23.377] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:15:0) after 9m30s
[02:43:53.403] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:15:0)
[02:43:53.441] Build status: STARTED
[02:43:53.441] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:16:0) after 4m49s
[02:48:42.926] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:16:0)
[02:48:43.007] Build status: STARTED
[02:48:43.008] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:17:0) after 8m23s
[02:57:06.497] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:17:0)
[02:57:06.519] Build status: STARTED
[02:57:06.519] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:18:0) after 3m17s
[03:00:23.650] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:18:0)
[03:00:23.674] Build status: STARTED
[03:00:23.674] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:19:0) after 9m22s
[03:09:45.790] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:19:0)
[03:09:45.813] Build status: STARTED
[03:09:45.814] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:20:0) after 9m1s
[03:18:46.787] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:20:0)
[03:18:46.846] Build status: STARTED
[03:18:46.846] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:21:0) after 1m7s
[03:19:53.913] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:21:0)
[03:19:53.932] Build status: STARTED
[03:19:53.932] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:22:0) after 3m55s
[03:23:48.952] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:22:0)
[03:23:48.969] Build status: STARTED
[03:23:48.969] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:23:0) after 1m30s
[03:25:18.946] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:23:0)
[03:25:18.965] Build status: STARTED
[03:25:18.965] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:24:0) after 2m32s
[03:27:51.020] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:24:0)
[03:27:51.038] Build status: STARTED
[03:27:51.038] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:25:0) after 7m17s
[03:35:08.063] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:25:0)
[03:35:08.081] Build status: STARTED
[03:35:08.081] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:26:0) after 6m31s
[03:41:39.099] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:26:0)
[03:41:39.117] Build status: STARTED
[03:41:39.117] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:27:0) after 6m26s
[03:48:05.132] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:27:0)
[03:48:05.149] Build status: STARTED
[03:48:05.149] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:28:0) after 2m55s
[03:51:00.170] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:28:0)
[03:51:00.218] Build status: STARTED
[03:51:00.218] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:29:0) after 1m7s
[03:52:07.253] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:29:0)
[03:52:07.271] Build status: STARTED
[03:52:07.271] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:30:0) after 1m11s
[03:53:18.297] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:30:0)
[03:53:18.314] Build status: STARTED
[03:53:18.314] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:31:0) after 6m41s
[03:59:59.375] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:31:0)
[03:59:59.393] Build status: STARTED
[03:59:59.393] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:32:0) after 7m30s
[04:07:29.399] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:32:0)
[04:07:29.460] Build status: STARTED
[04:07:29.460] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:33:0) after 8m56s
[04:16:25.468] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:33:0)
[04:16:25.484] Build status: STARTED
[04:16:25.484] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:34:0) after 9m43s
[04:26:08.507] Handling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:34:0)
[04:26:08.606] Build status: STARTED
[04:26:08.606] Scheduling timer "check-buildbucket-build-status" (chromium-m133/mac-official:8890421844139070480:35:0) after 2m17s
[04:27:05.680] Received PubSub notification, asking Buildbucket for the build status
[04:27:05.701] Build:
{
"id": "8724991782047625489",
"builder": {
"project": "chromium-m133",
"bucket": "ci",
"builder": "mac-official"
},
"number": 129,
"createdBy": "project:chromium-m133",
"createTime": "2025-01-23T01:40:48.813189209Z",
"startTime": "2025-01-23T01:40:54.796652905Z",
"endTime": "2025-01-23T04:27:05.282553170Z",
"updateTime": "2025-01-23T04:27:05.282553170Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "44e6a5074e562acf4bfb4b4881298738cc3bd2e7",
"ref": "refs/branch-heads/6943"
}
}
}
[04:27:05.701] Invocation finished in 2h46m18.266229304s with status SUCCEEDED