[21:20:00.058] New invocation is queued and will start shortly
[21:20:01.134] Starting the invocation (attempt 1)
[21:20:01.184] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/4f7bd056e51603684a575a8da53b630070d7a9ff"
[21:20:01.184] Popped gitiles commit info from properties and tags
[21:20:01.184] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[21:20:01.186] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[21:20:01.186] Buildbucket request:
{
"requestId": "8850484989429980368",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8850484989429980368",
"job": "infra/infra-continuous-win10-64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "36af0180b9796c1047116673df27103eec49e977"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@36af0180b9796c1047116673df27103eec49e977",
"title": "36af0180b9796c1047116673df27103eec49e977",
"url": "https://chromium.googlesource.com/infra/infra/+/36af0180b9796c1047116673df27103eec49e977"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "ca03e825a9bf6238259bb7abb5877d2637c1099d"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@ca03e825a9bf6238259bb7abb5877d2637c1099d",
"title": "ca03e825a9bf6238259bb7abb5877d2637c1099d",
"url": "https://chromium.googlesource.com/infra/infra/+/ca03e825a9bf6238259bb7abb5877d2637c1099d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "c42eca58123d0a8e791dccaf9653eafd55bb8664"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@c42eca58123d0a8e791dccaf9653eafd55bb8664",
"title": "c42eca58123d0a8e791dccaf9653eafd55bb8664",
"url": "https://chromium.googlesource.com/infra/infra/+/c42eca58123d0a8e791dccaf9653eafd55bb8664"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "9d2a3447242c9a982ccbe9fbe1b3538043bb9ad2"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@9d2a3447242c9a982ccbe9fbe1b3538043bb9ad2",
"title": "9d2a3447242c9a982ccbe9fbe1b3538043bb9ad2",
"url": "https://chromium.googlesource.com/infra/infra/+/9d2a3447242c9a982ccbe9fbe1b3538043bb9ad2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "4f7bd056e51603684a575a8da53b630070d7a9ff"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@4f7bd056e51603684a575a8da53b630070d7a9ff",
"title": "4f7bd056e51603684a575a8da53b630070d7a9ff",
"url": "https://chromium.googlesource.com/infra/infra/+/4f7bd056e51603684a575a8da53b630070d7a9ff"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "4f7bd056e51603684a575a8da53b630070d7a9ff",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8850484989429980368"
},
{
"key": "scheduler_job_id",
"value": "infra/infra-continuous-win10-64"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[21:20:02.807] Scheduled build:
{
"id": "8685054927507855889",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 37721,
"createdBy": "project:infra",
"createTime": "2026-04-08T21:20:01.266168996Z",
"updateTime": "2026-04-08T21:20:01.266168996Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "4f7bd056e51603684a575a8da53b630070d7a9ff",
"ref": "refs/heads/main"
}
}
}
[21:20:02.807] Task URL: https://cr-buildbucket-dev.appspot.com/build/8685054927507855889
[21:20:02.807] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:2:0) after 3m6s
[21:20:03.759] Received PubSub notification, asking Buildbucket for the build status
[21:20:03.783] Build status: SCHEDULED
[21:23:08.827] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:2:0)
[21:23:08.862] Build status: SCHEDULED
[21:23:08.862] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:4:0) after 1m35s
[21:24:43.886] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:4:0)
[21:24:43.908] Build status: SCHEDULED
[21:24:43.908] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:5:0) after 6m40s
[21:31:23.929] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:5:0)
[21:31:24.056] Build status: SCHEDULED
[21:31:24.056] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:6:0) after 4m41s
[21:36:05.935] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:6:0)
[21:36:05.984] Build status: SCHEDULED
[21:36:05.984] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:7:0) after 7m17s
[21:43:23.005] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:7:0)
[21:43:23.029] Build status: SCHEDULED
[21:43:23.029] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:8:0) after 5m33s
[21:48:56.044] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:8:0)
[21:48:56.077] Build status: SCHEDULED
[21:48:56.077] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:9:0) after 3m41s
[21:52:37.092] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:9:0)
[21:52:37.121] Build status: SCHEDULED
[21:52:37.121] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:10:0) after 6m27s
[21:59:04.138] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:10:0)
[21:59:04.159] Build status: SCHEDULED
[21:59:04.159] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:11:0) after 3m22s
[22:02:26.178] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:11:0)
[22:02:26.201] Build status: SCHEDULED
[22:02:26.201] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:12:0) after 7m34s
[22:06:42.482] Received PubSub notification, asking Buildbucket for the build status
[22:06:42.501] Build status: STARTED
[22:10:00.221] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:12:0)
[22:10:00.250] Build status: STARTED
[22:10:00.250] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:14:0) after 1m35s
[22:11:35.268] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:14:0)
[22:11:35.291] Build status: STARTED
[22:11:35.291] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:15:0) after 6m11s
[22:17:46.306] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:15:0)
[22:17:46.326] Build status: STARTED
[22:17:46.326] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:16:0) after 5m57s
[22:23:43.341] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:16:0)
[22:23:43.372] Build status: STARTED
[22:23:43.372] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:17:0) after 8m20s
[22:32:03.386] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:17:0)
[22:32:03.409] Build status: STARTED
[22:32:03.409] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8850484989429980368:18:0) after 6m53s
[22:33:18.477] Received PubSub notification, asking Buildbucket for the build status
[22:33:18.498] Build:
{
"id": "8685054927507855889",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 37721,
"createdBy": "project:infra",
"createTime": "2026-04-08T21:20:01.266168996Z",
"startTime": "2026-04-08T22:06:42.258768581Z",
"endTime": "2026-04-08T22:33:18.165577395Z",
"updateTime": "2026-04-08T22:33:18.165577395Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "4f7bd056e51603684a575a8da53b630070d7a9ff",
"ref": "refs/heads/main"
}
}
}
[22:33:18.498] Invocation finished in 1h13m18.448473028s with status SUCCEEDED