[20:46:12.123] New invocation is queued and will start shortly
[20:46:13.196] Starting the invocation (attempt 1)
[20:46:13.226] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/c893d318851acb847edcd64a58096bd414d673d2"
[20:46:13.226] Popped gitiles commit info from properties and tags
[20:46:13.226] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[20:46:13.226] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[20:46:13.226] Buildbucket request:
{
"requestId": "8939996918674636288",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8939996918674636288",
"job": "infra/infra-continuous-win11-64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "5a15fed46bbf9eb92a97c4f0319561d17ec05212"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@5a15fed46bbf9eb92a97c4f0319561d17ec05212",
"title": "5a15fed46bbf9eb92a97c4f0319561d17ec05212",
"url": "https://chromium.googlesource.com/infra/infra/+/5a15fed46bbf9eb92a97c4f0319561d17ec05212"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "7aafec4f1c260b094388453e379c71fca8db4673"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@7aafec4f1c260b094388453e379c71fca8db4673",
"title": "7aafec4f1c260b094388453e379c71fca8db4673",
"url": "https://chromium.googlesource.com/infra/infra/+/7aafec4f1c260b094388453e379c71fca8db4673"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "61fede4d57a30fce6989200fb56d3b3835582f74"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@61fede4d57a30fce6989200fb56d3b3835582f74",
"title": "61fede4d57a30fce6989200fb56d3b3835582f74",
"url": "https://chromium.googlesource.com/infra/infra/+/61fede4d57a30fce6989200fb56d3b3835582f74"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "94ec5c7fc3dcfab9c71fcc271bc88767e25eedec"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@94ec5c7fc3dcfab9c71fcc271bc88767e25eedec",
"title": "94ec5c7fc3dcfab9c71fcc271bc88767e25eedec",
"url": "https://chromium.googlesource.com/infra/infra/+/94ec5c7fc3dcfab9c71fcc271bc88767e25eedec"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "20660ae59b3d633c1b23dbb3f5b2f69fc0afc9ec"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@20660ae59b3d633c1b23dbb3f5b2f69fc0afc9ec",
"title": "20660ae59b3d633c1b23dbb3f5b2f69fc0afc9ec",
"url": "https://chromium.googlesource.com/infra/infra/+/20660ae59b3d633c1b23dbb3f5b2f69fc0afc9ec"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "c893d318851acb847edcd64a58096bd414d673d2"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@c893d318851acb847edcd64a58096bd414d673d2",
"title": "c893d318851acb847edcd64a58096bd414d673d2",
"url": "https://chromium.googlesource.com/infra/infra/+/c893d318851acb847edcd64a58096bd414d673d2"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "c893d318851acb847edcd64a58096bd414d673d2",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8939996918674636288"
},
{
"key": "scheduler_job_id",
"value": "infra/infra-continuous-win11-64"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[20:46:14.281] Scheduled build:
{
"id": "8774566856782243185",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"number": 3948,
"createdBy": "project:infra",
"createTime": "2023-07-25T20:46:13.305023119Z",
"updateTime": "2023-07-25T20:46:13.305023119Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "c893d318851acb847edcd64a58096bd414d673d2",
"ref": "refs/heads/main"
}
}
}
[20:46:14.281] Task URL: https://cr-buildbucket-dev.appspot.com/build/8774566856782243185
[20:46:14.281] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:2:0) after 9m34s
[20:46:17.903] Received PubSub notification, asking Buildbucket for the build status
[20:46:17.929] Build status: SCHEDULED
[20:55:48.308] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:2:0)
[20:55:48.332] Build status: SCHEDULED
[20:55:48.332] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:4:0) after 3m53s
[20:59:41.347] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:4:0)
[20:59:41.390] Build status: SCHEDULED
[20:59:41.390] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:5:0) after 4m2s
[21:03:43.407] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:5:0)
[21:03:43.437] Build status: SCHEDULED
[21:03:43.437] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:6:0) after 9m57s
[21:13:40.454] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:6:0)
[21:13:40.503] Build status: SCHEDULED
[21:13:40.503] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:7:0) after 9m7s
[21:22:47.518] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:7:0)
[21:22:47.546] Build status: SCHEDULED
[21:22:47.546] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:8:0) after 4m30s
[21:27:17.566] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:8:0)
[21:27:17.600] Build status: SCHEDULED
[21:27:17.600] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:9:0) after 5m26s
[21:32:43.618] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:9:0)
[21:32:43.672] Build status: SCHEDULED
[21:32:43.672] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:10:0) after 4m13s
[21:36:56.690] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:10:0)
[21:36:56.723] Build status: SCHEDULED
[21:36:56.723] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:11:0) after 6m29s
[21:43:25.741] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:11:0)
[21:43:25.769] Build status: SCHEDULED
[21:43:25.769] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:12:0) after 7m47s
[21:51:12.800] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:12:0)
[21:51:12.857] Build status: SCHEDULED
[21:51:12.857] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:13:0) after 8m17s
[21:59:29.873] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:13:0)
[21:59:29.896] Build status: SCHEDULED
[21:59:29.896] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:14:0) after 1m59s
[22:01:28.913] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:14:0)
[22:01:28.961] Build status: SCHEDULED
[22:01:28.961] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:15:0) after 8m26s
[22:09:54.977] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:15:0)
[22:09:55.046] Build status: SCHEDULED
[22:09:55.046] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:16:0) after 6m50s
[22:16:45.061] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:16:0)
[22:16:45.106] Build status: SCHEDULED
[22:16:45.106] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:17:0) after 3m10s
[22:19:55.121] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:17:0)
[22:19:55.146] Build status: SCHEDULED
[22:19:55.146] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:18:0) after 9m44s
[22:29:39.179] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:18:0)
[22:29:39.206] Build status: SCHEDULED
[22:29:39.206] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:19:0) after 1m8s
[22:30:47.231] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:19:0)
[22:30:47.259] Build status: SCHEDULED
[22:30:47.259] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:20:0) after 4m15s
[22:35:02.279] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:20:0)
[22:35:02.303] Build status: SCHEDULED
[22:35:02.303] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:21:0) after 9m21s
[22:44:23.325] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:21:0)
[22:44:23.392] Build status: SCHEDULED
[22:44:23.392] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:22:0) after 4m26s
[22:48:49.409] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:22:0)
[22:48:49.442] Build status: SCHEDULED
[22:48:49.442] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:23:0) after 9m58s
[22:58:47.462] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:23:0)
[22:58:47.482] Build status: SCHEDULED
[22:58:47.482] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:24:0) after 7m48s
[23:06:35.521] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:24:0)
[23:06:35.550] Build status: SCHEDULED
[23:06:35.550] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:25:0) after 4m4s
[23:10:39.564] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:25:0)
[23:10:39.587] Build status: SCHEDULED
[23:10:39.587] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:26:0) after 9m28s
[23:20:07.613] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:26:0)
[23:20:07.645] Build status: SCHEDULED
[23:20:07.645] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:27:0) after 1m41s
[23:21:48.664] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:27:0)
[23:21:48.688] Build status: SCHEDULED
[23:21:48.688] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:28:0) after 1m44s
[23:23:32.707] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:28:0)
[23:23:32.745] Build status: SCHEDULED
[23:23:32.745] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:29:0) after 6m0s
[23:29:32.762] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:29:0)
[23:29:32.781] Build status: SCHEDULED
[23:29:32.781] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:30:0) after 2m44s
[23:32:16.799] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:30:0)
[23:32:16.834] Build status: SCHEDULED
[23:32:16.834] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:31:0) after 5m4s
[23:37:20.856] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:31:0)
[23:37:20.899] Build status: SCHEDULED
[23:37:20.899] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:32:0) after 1m56s
[23:39:16.920] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:32:0)
[23:39:16.941] Build status: SCHEDULED
[23:39:16.941] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:33:0) after 3m30s
[23:42:46.966] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:33:0)
[23:42:46.994] Build status: STARTED
[23:42:46.994] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:34:0) after 6m26s
[23:42:57.395] Received PubSub notification, asking Buildbucket for the build status
[23:42:57.419] Build status: STARTED
[23:49:13.010] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:34:0)
[23:49:13.047] Build status: STARTED
[23:49:13.047] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:36:0) after 3m7s
[23:52:20.067] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:36:0)
[23:52:20.097] Build status: STARTED
[23:52:20.097] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:37:0) after 7m59s
[00:00:19.127] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:37:0)
[00:00:19.164] Build status: STARTED
[00:00:19.164] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8939996918674636288:38:0) after 3m51s
[00:03:29.419] Received PubSub notification, asking Buildbucket for the build status
[00:03:29.458] Build:
{
"id": "8774566856782243185",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"number": 3948,
"createdBy": "project:infra",
"createTime": "2023-07-25T20:46:13.305023119Z",
"startTime": "2023-07-25T23:42:46.581661Z",
"endTime": "2023-07-26T00:03:28.088390857Z",
"updateTime": "2023-07-26T00:03:28.088390857Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "c893d318851acb847edcd64a58096bd414d673d2",
"ref": "refs/heads/main"
}
}
}
[00:03:29.458] Invocation finished in 3h17m17.341312336s with status SUCCEEDED