[15:51:29.410] New invocation is queued and will start shortly
[15:51:30.804] Starting the invocation (attempt 1)
[15:51:30.841] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4440219895caa2e57d7ababeae937a2bbcd9f1fe"
[15:51:30.841] Popped gitiles commit info from properties and tags
[15:51:30.841] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:51:30.842] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:51:30.842] Buildbucket request:
{
"requestId": "8884570116911600928",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8884570116911600928",
"job": "chromium-m134/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fd64f2ea6d777f57058d737cf8fe95576dce2c86"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@fd64f2ea6d777f57058d737cf8fe95576dce2c86",
"title": "fd64f2ea6d777f57058d737cf8fe95576dce2c86",
"url": "https://chromium.googlesource.com/chromium/src/+/fd64f2ea6d777f57058d737cf8fe95576dce2c86"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ba47d67a4becb86ad1fff219ddfd5abcaf63983c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@ba47d67a4becb86ad1fff219ddfd5abcaf63983c",
"title": "ba47d67a4becb86ad1fff219ddfd5abcaf63983c",
"url": "https://chromium.googlesource.com/chromium/src/+/ba47d67a4becb86ad1fff219ddfd5abcaf63983c"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0c4df9b5cad1ffb414b9ee58a5391229fe7770ed"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@0c4df9b5cad1ffb414b9ee58a5391229fe7770ed",
"title": "0c4df9b5cad1ffb414b9ee58a5391229fe7770ed",
"url": "https://chromium.googlesource.com/chromium/src/+/0c4df9b5cad1ffb414b9ee58a5391229fe7770ed"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fc814dd219628235d66dd87291d1a9811e937aa1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@fc814dd219628235d66dd87291d1a9811e937aa1",
"title": "fc814dd219628235d66dd87291d1a9811e937aa1",
"url": "https://chromium.googlesource.com/chromium/src/+/fc814dd219628235d66dd87291d1a9811e937aa1"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1ace61dc910ffa2ef5676dec17625ea177ec4ef2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@1ace61dc910ffa2ef5676dec17625ea177ec4ef2",
"title": "1ace61dc910ffa2ef5676dec17625ea177ec4ef2",
"url": "https://chromium.googlesource.com/chromium/src/+/1ace61dc910ffa2ef5676dec17625ea177ec4ef2"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3cb1c420b6ab3c9e45306962d6f90aee0c61b114"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@3cb1c420b6ab3c9e45306962d6f90aee0c61b114",
"title": "3cb1c420b6ab3c9e45306962d6f90aee0c61b114",
"url": "https://chromium.googlesource.com/chromium/src/+/3cb1c420b6ab3c9e45306962d6f90aee0c61b114"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "09ac50fc25b5f3ef1b06c30d46206386dce28e0c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@09ac50fc25b5f3ef1b06c30d46206386dce28e0c",
"title": "09ac50fc25b5f3ef1b06c30d46206386dce28e0c",
"url": "https://chromium.googlesource.com/chromium/src/+/09ac50fc25b5f3ef1b06c30d46206386dce28e0c"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ac325db5af8143b0e82d986037b942eb3e290fad"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@ac325db5af8143b0e82d986037b942eb3e290fad",
"title": "ac325db5af8143b0e82d986037b942eb3e290fad",
"url": "https://chromium.googlesource.com/chromium/src/+/ac325db5af8143b0e82d986037b942eb3e290fad"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4440219895caa2e57d7ababeae937a2bbcd9f1fe"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@4440219895caa2e57d7ababeae937a2bbcd9f1fe",
"title": "4440219895caa2e57d7ababeae937a2bbcd9f1fe",
"url": "https://chromium.googlesource.com/chromium/src/+/4440219895caa2e57d7ababeae937a2bbcd9f1fe"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4440219895caa2e57d7ababeae937a2bbcd9f1fe",
"ref": "refs/branch-heads/6998"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8884570116911600928"
},
{
"key": "scheduler_job_id",
"value": "chromium-m134/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[15:51:31.620] Scheduled build:
{
"id": "8719140054536550769",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "win-official"
},
"number": 417,
"createdBy": "project:chromium-m134",
"createTime": "2025-03-28T15:51:31.047862179Z",
"updateTime": "2025-03-28T15:51:31.047862179Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4440219895caa2e57d7ababeae937a2bbcd9f1fe",
"ref": "refs/branch-heads/6998"
}
}
}
[15:51:31.620] Task URL: https://cr-buildbucket.appspot.com/build/8719140054536550769
[15:51:31.620] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:2:0) after 7m38s
[15:51:32.599] Received PubSub notification, asking Buildbucket for the build status
[15:51:32.622] Build status: SCHEDULED
[15:51:36.814] Received PubSub notification, asking Buildbucket for the build status
[15:51:36.853] Build status: STARTED
[15:59:09.636] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:2:0)
[15:59:09.673] Build status: STARTED
[15:59:09.673] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:5:0) after 7m48s
[16:06:57.734] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:5:0)
[16:06:57.765] Build status: STARTED
[16:06:57.765] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:6:0) after 8m34s
[16:15:31.788] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:6:0)
[16:15:31.805] Build status: STARTED
[16:15:31.805] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:7:0) after 3m59s
[16:19:30.829] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:7:0)
[16:19:30.885] Build status: STARTED
[16:19:30.885] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:8:0) after 5m39s
[16:25:09.949] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:8:0)
[16:25:09.975] Build status: STARTED
[16:25:09.975] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:9:0) after 7m44s
[16:32:53.990] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:9:0)
[16:32:54.016] Build status: STARTED
[16:32:54.016] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:10:0) after 3m35s
[16:36:29.042] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:10:0)
[16:36:29.068] Build status: STARTED
[16:36:29.068] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:11:0) after 3m35s
[16:40:04.095] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:11:0)
[16:40:04.113] Build status: STARTED
[16:40:04.113] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:12:0) after 3m18s
[16:43:22.166] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:12:0)
[16:43:22.182] Build status: STARTED
[16:43:22.182] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:13:0) after 8m23s
[16:51:45.208] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:13:0)
[16:51:45.240] Build status: STARTED
[16:51:45.240] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:14:0) after 4m5s
[16:55:50.288] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:14:0)
[16:55:50.305] Build status: STARTED
[16:55:50.305] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:15:0) after 9m6s
[17:04:56.330] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:15:0)
[17:04:56.349] Build status: STARTED
[17:04:56.349] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:16:0) after 8m23s
[17:13:19.364] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:16:0)
[17:13:19.383] Build status: STARTED
[17:13:19.383] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:17:0) after 3m37s
[17:16:56.440] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:17:0)
[17:16:56.457] Build status: STARTED
[17:16:56.457] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:18:0) after 2m2s
[17:18:58.484] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:18:0)
[17:18:58.501] Build status: STARTED
[17:18:58.501] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:19:0) after 3m27s
[17:22:25.543] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:19:0)
[17:22:25.610] Build status: STARTED
[17:22:25.610] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:20:0) after 4m37s
[17:27:02.627] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:20:0)
[17:27:02.647] Build status: STARTED
[17:27:02.647] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:21:0) after 5m52s
[17:32:54.664] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:21:0)
[17:32:54.726] Build status: STARTED
[17:32:54.726] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:22:0) after 4m40s
[17:37:34.785] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:22:0)
[17:37:34.810] Build status: STARTED
[17:37:34.810] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:23:0) after 8m29s
[17:46:03.840] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:23:0)
[17:46:03.858] Build status: STARTED
[17:46:03.858] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:24:0) after 2m29s
[17:48:32.901] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:24:0)
[17:48:32.937] Build status: STARTED
[17:48:32.937] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:25:0) after 1m29s
[17:50:01.964] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:25:0)
[17:50:02.012] Build status: STARTED
[17:50:02.012] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:26:0) after 1m44s
[17:51:46.032] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:26:0)
[17:51:46.088] Build status: STARTED
[17:51:46.088] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:27:0) after 1m41s
[17:53:27.086] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:27:0)
[17:53:27.119] Build status: STARTED
[17:53:27.119] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:28:0) after 7m25s
[18:00:52.143] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:28:0)
[18:00:52.161] Build status: STARTED
[18:00:52.161] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:29:0) after 9m10s
[18:10:02.222] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:29:0)
[18:10:02.250] Build status: STARTED
[18:10:02.250] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:30:0) after 6m8s
[18:16:10.413] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:30:0)
[18:16:10.437] Build status: STARTED
[18:16:10.437] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:31:0) after 1m7s
[18:17:17.424] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:31:0)
[18:17:17.516] Build status: STARTED
[18:17:17.516] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:32:0) after 1m57s
[18:19:14.587] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:32:0)
[18:19:14.605] Build status: STARTED
[18:19:14.605] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:33:0) after 8m35s
[18:27:49.620] Handling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:33:0)
[18:27:49.637] Build status: STARTED
[18:27:49.637] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win-official:8884570116911600928:34:0) after 7m5s
[18:33:59.504] Received PubSub notification, asking Buildbucket for the build status
[18:33:59.522] Build:
{
"id": "8719140054536550769",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "win-official"
},
"number": 417,
"createdBy": "project:chromium-m134",
"createTime": "2025-03-28T15:51:31.047862179Z",
"startTime": "2025-03-28T15:51:36.617249260Z",
"endTime": "2025-03-28T18:33:52.871848946Z",
"updateTime": "2025-03-28T18:33:52.871848946Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4440219895caa2e57d7ababeae937a2bbcd9f1fe",
"ref": "refs/branch-heads/6998"
}
}
}
[18:33:59.522] Invocation finished in 2h42m30.123477247s with status SUCCEEDED