[21:22:58.375] New invocation is queued and will start shortly
[21:22:59.539] Starting the invocation (attempt 1)
[21:22:59.566] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2cc0487a833782307b27d95b5170f7fff9161eb1"
[21:22:59.566] Popped gitiles commit info from properties and tags
[21:22:59.566] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:22:59.567] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:22:59.567] Buildbucket request:
{
"requestId": "8898863582507364912",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8898863582507364912",
"job": "chromium-m131/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ad01bc54f5441c6b167ba7012441f9f19cb4e40f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@ad01bc54f5441c6b167ba7012441f9f19cb4e40f",
"title": "ad01bc54f5441c6b167ba7012441f9f19cb4e40f",
"url": "https://chromium.googlesource.com/chromium/src/+/ad01bc54f5441c6b167ba7012441f9f19cb4e40f"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bbe26ab65736de7bf3b806b5dc33af45bd9ce366"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@bbe26ab65736de7bf3b806b5dc33af45bd9ce366",
"title": "bbe26ab65736de7bf3b806b5dc33af45bd9ce366",
"url": "https://chromium.googlesource.com/chromium/src/+/bbe26ab65736de7bf3b806b5dc33af45bd9ce366"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8a9f17f9b66377545bcc8ae96c99368cc58421bc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@8a9f17f9b66377545bcc8ae96c99368cc58421bc",
"title": "8a9f17f9b66377545bcc8ae96c99368cc58421bc",
"url": "https://chromium.googlesource.com/chromium/src/+/8a9f17f9b66377545bcc8ae96c99368cc58421bc"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c575649693e834559c40d11e0c612e4d34e6d8e1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@c575649693e834559c40d11e0c612e4d34e6d8e1",
"title": "c575649693e834559c40d11e0c612e4d34e6d8e1",
"url": "https://chromium.googlesource.com/chromium/src/+/c575649693e834559c40d11e0c612e4d34e6d8e1"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "57bd3b39be8230edca5ecffcc1b326f9d5e2d78f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@57bd3b39be8230edca5ecffcc1b326f9d5e2d78f",
"title": "57bd3b39be8230edca5ecffcc1b326f9d5e2d78f",
"url": "https://chromium.googlesource.com/chromium/src/+/57bd3b39be8230edca5ecffcc1b326f9d5e2d78f"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "614d4b4f168120c57cecf4518a8b1716a22edc8f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@614d4b4f168120c57cecf4518a8b1716a22edc8f",
"title": "614d4b4f168120c57cecf4518a8b1716a22edc8f",
"url": "https://chromium.googlesource.com/chromium/src/+/614d4b4f168120c57cecf4518a8b1716a22edc8f"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2885a8ae4765ef9156618fc2fb09a2a5d056299f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@2885a8ae4765ef9156618fc2fb09a2a5d056299f",
"title": "2885a8ae4765ef9156618fc2fb09a2a5d056299f",
"url": "https://chromium.googlesource.com/chromium/src/+/2885a8ae4765ef9156618fc2fb09a2a5d056299f"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "de46f392fab4e17b8ee5a0b8d0ef185936ab23ee"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@de46f392fab4e17b8ee5a0b8d0ef185936ab23ee",
"title": "de46f392fab4e17b8ee5a0b8d0ef185936ab23ee",
"url": "https://chromium.googlesource.com/chromium/src/+/de46f392fab4e17b8ee5a0b8d0ef185936ab23ee"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f49784110b58d82d7e247395c3c8dfc88bb56bb0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@f49784110b58d82d7e247395c3c8dfc88bb56bb0",
"title": "f49784110b58d82d7e247395c3c8dfc88bb56bb0",
"url": "https://chromium.googlesource.com/chromium/src/+/f49784110b58d82d7e247395c3c8dfc88bb56bb0"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "624251549c7814ed42a8fd5de445800cf0db2ad7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@624251549c7814ed42a8fd5de445800cf0db2ad7",
"title": "624251549c7814ed42a8fd5de445800cf0db2ad7",
"url": "https://chromium.googlesource.com/chromium/src/+/624251549c7814ed42a8fd5de445800cf0db2ad7"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "187b33c5f27a8e1b3b338b917313ecdc8635d59e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@187b33c5f27a8e1b3b338b917313ecdc8635d59e",
"title": "187b33c5f27a8e1b3b338b917313ecdc8635d59e",
"url": "https://chromium.googlesource.com/chromium/src/+/187b33c5f27a8e1b3b338b917313ecdc8635d59e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2cc0487a833782307b27d95b5170f7fff9161eb1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@2cc0487a833782307b27d95b5170f7fff9161eb1",
"title": "2cc0487a833782307b27d95b5170f7fff9161eb1",
"url": "https://chromium.googlesource.com/chromium/src/+/2cc0487a833782307b27d95b5170f7fff9161eb1"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2cc0487a833782307b27d95b5170f7fff9161eb1",
"ref": "refs/branch-heads/6778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8898863582507364912"
},
{
"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"
}
}
[21:23:00.065] Scheduled build:
{
"id": "8733433520532808033",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-official"
},
"number": 59,
"createdBy": "project:chromium-m131",
"createTime": "2024-10-21T21:22:59.634642551Z",
"updateTime": "2024-10-21T21:22:59.634642551Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2cc0487a833782307b27d95b5170f7fff9161eb1",
"ref": "refs/branch-heads/6778"
}
}
}
[21:23:00.065] Task URL: https://cr-buildbucket.appspot.com/build/8733433520532808033
[21:23:00.065] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:2:0) after 1m3s
[21:23:00.314] Received PubSub notification, asking Buildbucket for the build status
[21:23:00.340] Build status: SCHEDULED
[21:23:07.497] Received PubSub notification, asking Buildbucket for the build status
[21:23:07.534] Build status: STARTED
[21:24:03.097] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:2:0)
[21:24:03.118] Build status: STARTED
[21:24:03.118] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:5:0) after 7m36s
[21:31:39.190] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:5:0)
[21:31:39.239] Build status: STARTED
[21:31:39.239] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:6:0) after 6m18s
[21:37:57.312] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:6:0)
[21:37:57.330] Build status: STARTED
[21:37:57.330] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:7:0) after 8m47s
[21:46:44.397] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:7:0)
[21:46:44.462] Build status: STARTED
[21:46:44.462] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:8:0) after 4m47s
[21:51:31.567] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:8:0)
[21:51:31.586] Build status: STARTED
[21:51:31.586] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:9:0) after 6m18s
[21:57:49.600] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:9:0)
[21:57:49.621] Build status: STARTED
[21:57:49.621] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:10:0) after 2m37s
[22:00:26.691] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:10:0)
[22:00:26.717] Build status: STARTED
[22:00:26.717] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:11:0) after 3m56s
[22:04:22.692] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:11:0)
[22:04:22.722] Build status: STARTED
[22:04:22.722] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:12:0) after 9m17s
[22:13:39.749] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:12:0)
[22:13:39.767] Build status: STARTED
[22:13:39.767] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:13:0) after 7m8s
[22:20:47.784] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:13:0)
[22:20:47.799] Build status: STARTED
[22:20:47.799] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:14:0) after 1m39s
[22:22:26.818] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:14:0)
[22:22:26.836] Build status: STARTED
[22:22:26.836] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:15:0) after 3m34s
[22:26:00.897] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:15:0)
[22:26:00.916] Build status: STARTED
[22:26:00.916] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:16:0) after 3m29s
[22:29:29.935] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:16:0)
[22:29:29.956] Build status: STARTED
[22:29:29.956] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:17:0) after 1m22s
[22:30:52.050] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:17:0)
[22:30:52.098] Build status: STARTED
[22:30:52.098] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:18:0) after 4m0s
[22:34:52.116] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:18:0)
[22:34:52.134] Build status: STARTED
[22:34:52.135] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:19:0) after 7m49s
[22:42:41.277] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:19:0)
[22:42:41.340] Build status: STARTED
[22:42:41.340] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:20:0) after 9m14s
[22:51:55.359] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:20:0)
[22:51:55.427] Build status: STARTED
[22:51:55.427] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:21:0) after 6m44s
[22:58:39.450] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:21:0)
[22:58:39.470] Build status: STARTED
[22:58:39.470] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:22:0) after 8m44s
[23:07:23.496] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:22:0)
[23:07:23.518] Build status: STARTED
[23:07:23.518] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:23:0) after 2m13s
[23:09:36.568] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:23:0)
[23:09:36.590] Build status: STARTED
[23:09:36.590] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:24:0) after 9m2s
[23:18:38.607] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:24:0)
[23:18:38.679] Build status: STARTED
[23:18:38.679] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:25:0) after 7m36s
[23:26:14.704] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:25:0)
[23:26:14.749] Build status: STARTED
[23:26:14.750] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:26:0) after 5m21s
[23:31:35.815] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:26:0)
[23:31:35.834] Build status: STARTED
[23:31:35.834] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:27:0) after 8m32s
[23:40:07.850] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:27:0)
[23:40:07.871] Build status: STARTED
[23:40:07.871] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:28:0) after 6m31s
[23:46:38.933] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:28:0)
[23:46:38.951] Build status: STARTED
[23:46:38.951] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:29:0) after 5m43s
[23:52:21.970] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:29:0)
[23:52:21.991] Build status: STARTED
[23:52:21.991] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:30:0) after 6m57s
[23:59:19.016] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:30:0)
[23:59:19.034] Build status: STARTED
[23:59:19.034] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898863582507364912:31:0) after 6m35s
[00:03:57.702] Received PubSub notification, asking Buildbucket for the build status
[00:03:57.717] Build:
{
"id": "8733433520532808033",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-official"
},
"number": 59,
"createdBy": "project:chromium-m131",
"createTime": "2024-10-21T21:22:59.634642551Z",
"startTime": "2024-10-21T21:23:07.180829443Z",
"endTime": "2024-10-22T00:03:57.145447708Z",
"updateTime": "2024-10-22T00:03:57.145447708Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2cc0487a833782307b27d95b5170f7fff9161eb1",
"ref": "refs/branch-heads/6778"
}
}
}
[00:03:57.717] Invocation finished in 2h40m59.350145679s with status SUCCEEDED