[13:26:44.683] New invocation is queued and will start shortly
[13:26:45.844] Starting the invocation (attempt 1)
[13:26:45.869] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a1fca13316dad6b42c1aa6dfeb63c4c0ce67c3c5"
[13:26:45.869] Popped gitiles commit info from properties and tags
[13:26:45.869] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:26:45.869] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:26:45.869] Buildbucket request:
{
"requestId": "8892461159577641440",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8892461159577641440",
"job": "chromium-m131/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e1c08f94af5d24b579c43f74a5ebffdee4f45ae9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@e1c08f94af5d24b579c43f74a5ebffdee4f45ae9",
"title": "e1c08f94af5d24b579c43f74a5ebffdee4f45ae9",
"url": "https://chromium.googlesource.com/chromium/src/+/e1c08f94af5d24b579c43f74a5ebffdee4f45ae9"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "39c871e96b70ed367b704974d91771c226d17265"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@39c871e96b70ed367b704974d91771c226d17265",
"title": "39c871e96b70ed367b704974d91771c226d17265",
"url": "https://chromium.googlesource.com/chromium/src/+/39c871e96b70ed367b704974d91771c226d17265"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e1a1fbece4caa7a2fd29ba029e2279923b7cac27"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@e1a1fbece4caa7a2fd29ba029e2279923b7cac27",
"title": "e1a1fbece4caa7a2fd29ba029e2279923b7cac27",
"url": "https://chromium.googlesource.com/chromium/src/+/e1a1fbece4caa7a2fd29ba029e2279923b7cac27"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "60195781c23f6b13ef989c694860cb8322113778"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@60195781c23f6b13ef989c694860cb8322113778",
"title": "60195781c23f6b13ef989c694860cb8322113778",
"url": "https://chromium.googlesource.com/chromium/src/+/60195781c23f6b13ef989c694860cb8322113778"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d89e9e1003fe78ffef3ab613720c44dd92c1c08d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@d89e9e1003fe78ffef3ab613720c44dd92c1c08d",
"title": "d89e9e1003fe78ffef3ab613720c44dd92c1c08d",
"url": "https://chromium.googlesource.com/chromium/src/+/d89e9e1003fe78ffef3ab613720c44dd92c1c08d"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6244ba25f78d855a6e41f724c3b6c217793f5c9a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@6244ba25f78d855a6e41f724c3b6c217793f5c9a",
"title": "6244ba25f78d855a6e41f724c3b6c217793f5c9a",
"url": "https://chromium.googlesource.com/chromium/src/+/6244ba25f78d855a6e41f724c3b6c217793f5c9a"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7d4d311e646e603c34189511fe320cb77674e5c0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@7d4d311e646e603c34189511fe320cb77674e5c0",
"title": "7d4d311e646e603c34189511fe320cb77674e5c0",
"url": "https://chromium.googlesource.com/chromium/src/+/7d4d311e646e603c34189511fe320cb77674e5c0"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a1fca13316dad6b42c1aa6dfeb63c4c0ce67c3c5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@a1fca13316dad6b42c1aa6dfeb63c4c0ce67c3c5",
"title": "a1fca13316dad6b42c1aa6dfeb63c4c0ce67c3c5",
"url": "https://chromium.googlesource.com/chromium/src/+/a1fca13316dad6b42c1aa6dfeb63c4c0ce67c3c5"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a1fca13316dad6b42c1aa6dfeb63c4c0ce67c3c5",
"ref": "refs/branch-heads/6778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8892461159577641440"
},
{
"key": "scheduler_job_id",
"value": "chromium-m131/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[13:26:46.709] Scheduled build:
{
"id": "8727031097632259537",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-official"
},
"number": 540,
"createdBy": "project:chromium-m131",
"createTime": "2024-12-31T13:26:45.917296249Z",
"updateTime": "2024-12-31T13:26:45.917296249Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a1fca13316dad6b42c1aa6dfeb63c4c0ce67c3c5",
"ref": "refs/branch-heads/6778"
}
}
}
[13:26:46.709] Task URL: https://cr-buildbucket.appspot.com/build/8727031097632259537
[13:26:46.709] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:2:0) after 8m3s
[13:26:47.627] Received PubSub notification, asking Buildbucket for the build status
[13:26:47.644] Build status: SCHEDULED
[13:26:52.011] Received PubSub notification, asking Buildbucket for the build status
[13:26:52.037] Build status: STARTED
[13:34:49.753] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:2:0)
[13:34:49.797] Build status: STARTED
[13:34:49.797] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:5:0) after 7m16s
[13:42:05.924] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:5:0)
[13:42:05.948] Build status: STARTED
[13:42:05.948] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:6:0) after 2m13s
[13:44:19.005] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:6:0)
[13:44:19.033] Build status: STARTED
[13:44:19.033] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:7:0) after 4m21s
[13:48:40.254] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:7:0)
[13:48:40.274] Build status: STARTED
[13:48:40.274] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:8:0) after 3m35s
[13:52:15.296] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:8:0)
[13:52:15.317] Build status: STARTED
[13:52:15.317] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:9:0) after 6m44s
[13:58:59.399] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:9:0)
[13:58:59.419] Build status: STARTED
[13:58:59.419] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:10:0) after 7m4s
[14:06:03.492] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:10:0)
[14:06:03.513] Build status: STARTED
[14:06:03.513] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:11:0) after 5m20s
[14:11:23.594] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:11:0)
[14:11:23.615] Build status: STARTED
[14:11:23.615] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:12:0) after 6m16s
[14:17:39.638] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:12:0)
[14:17:39.674] Build status: STARTED
[14:17:39.674] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:13:0) after 6m40s
[14:24:19.695] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:13:0)
[14:24:19.724] Build status: STARTED
[14:24:19.724] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:14:0) after 3m48s
[14:28:07.850] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:14:0)
[14:28:07.879] Build status: STARTED
[14:28:07.879] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:15:0) after 2m43s
[14:30:50.901] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:15:0)
[14:30:50.920] Build status: STARTED
[14:30:50.920] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:16:0) after 3m16s
[14:34:07.037] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:16:0)
[14:34:07.079] Build status: STARTED
[14:34:07.079] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:17:0) after 5m48s
[14:39:55.095] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:17:0)
[14:39:55.119] Build status: STARTED
[14:39:55.119] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:18:0) after 4m28s
[14:44:23.140] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:18:0)
[14:44:23.212] Build status: STARTED
[14:44:23.212] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:19:0) after 3m52s
[14:48:15.239] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:19:0)
[14:48:15.257] Build status: STARTED
[14:48:15.257] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:20:0) after 6m35s
[14:54:50.279] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:20:0)
[14:54:50.353] Build status: STARTED
[14:54:50.353] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:21:0) after 8m23s
[15:03:13.380] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:21:0)
[15:03:13.406] Build status: STARTED
[15:03:13.406] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:22:0) after 9m52s
[15:13:05.529] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:22:0)
[15:13:05.578] Build status: STARTED
[15:13:05.578] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:23:0) after 2m14s
[15:15:19.684] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:23:0)
[15:15:19.701] Build status: STARTED
[15:15:19.701] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:24:0) after 1m55s
[15:17:14.721] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:24:0)
[15:17:14.746] Build status: STARTED
[15:17:14.746] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:25:0) after 4m46s
[15:22:00.779] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:25:0)
[15:22:00.805] Build status: STARTED
[15:22:00.805] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:26:0) after 4m28s
[15:26:28.936] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:26:0)
[15:26:28.960] Build status: STARTED
[15:26:28.960] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:27:0) after 7m48s
[15:34:17.102] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:27:0)
[15:34:17.136] Build status: STARTED
[15:34:17.136] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:28:0) after 1m21s
[15:35:38.258] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:28:0)
[15:35:38.273] Build status: STARTED
[15:35:38.274] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:29:0) after 5m28s
[15:41:06.297] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:29:0)
[15:41:06.324] Build status: STARTED
[15:41:06.324] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:30:0) after 2m41s
[15:43:47.401] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:30:0)
[15:43:47.422] Build status: STARTED
[15:43:47.422] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:31:0) after 8m14s
[15:52:01.438] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:31:0)
[15:52:01.459] Build status: STARTED
[15:52:01.459] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:32:0) after 6m59s
[15:59:00.487] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:32:0)
[15:59:00.618] Build status: STARTED
[15:59:00.618] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:33:0) after 8m33s
[16:07:33.715] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:33:0)
[16:07:33.732] Build status: STARTED
[16:07:33.732] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:34:0) after 4m11s
[16:11:44.741] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:34:0)
[16:11:44.763] Build status: STARTED
[16:11:44.763] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:35:0) after 3m30s
[16:15:14.902] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:35:0)
[16:15:14.918] Build status: STARTED
[16:15:14.918] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:36:0) after 2m8s
[16:17:22.966] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:36:0)
[16:17:22.984] Build status: STARTED
[16:17:22.984] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:37:0) after 6m16s
[16:23:39.343] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:37:0)
[16:23:39.359] Build status: STARTED
[16:23:39.359] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:38:0) after 2m4s
[16:25:43.561] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:38:0)
[16:25:43.578] Build status: STARTED
[16:25:43.578] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:39:0) after 4m22s
[16:30:05.697] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:39:0)
[16:30:05.724] Build status: STARTED
[16:30:05.724] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:40:0) after 7m9s
[16:37:14.826] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:40:0)
[16:37:14.907] Build status: STARTED
[16:37:14.907] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:41:0) after 4m0s
[16:41:14.927] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:41:0)
[16:41:14.953] Build status: STARTED
[16:41:14.953] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:42:0) after 9m21s
[16:50:35.985] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:42:0)
[16:50:36.043] Build status: STARTED
[16:50:36.043] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892461159577641440:43:0) after 9m24s
[16:58:47.637] Received PubSub notification, asking Buildbucket for the build status
[16:58:47.660] Build:
{
"id": "8727031097632259537",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-official"
},
"number": 540,
"createdBy": "project:chromium-m131",
"createTime": "2024-12-31T13:26:45.917296249Z",
"startTime": "2024-12-31T13:26:51.839472966Z",
"endTime": "2024-12-31T16:58:47.206170611Z",
"updateTime": "2024-12-31T16:58:47.206170611Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a1fca13316dad6b42c1aa6dfeb63c4c0ce67c3c5",
"ref": "refs/branch-heads/6778"
}
}
}
[16:58:47.660] Invocation finished in 3h32m2.981749729s with status SUCCEEDED