[21:18:56.073] New invocation is queued and will start shortly
[21:18:57.168] Starting the invocation (attempt 1)
[21:18:57.206] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/ee46b66fb03cf8356370d5f67c0a78283ff44159"
[21:18:57.206] Popped gitiles commit info from properties and tags
[21:18:57.206] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[21:18:57.206] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[21:18:57.206] Buildbucket request:
{
"requestId": "8973062752071602048",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8973062752071602048",
"job": "infra/infra-continuous-win11-64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "4d2edcfe6c5d333c9bd3564ef5607c1a28aa537b"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@4d2edcfe6c5d333c9bd3564ef5607c1a28aa537b",
"title": "4d2edcfe6c5d333c9bd3564ef5607c1a28aa537b",
"url": "https://chromium.googlesource.com/infra/infra/+/4d2edcfe6c5d333c9bd3564ef5607c1a28aa537b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "102012bb5d7113b2da6d29a3192e75b2d6a91c29"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@102012bb5d7113b2da6d29a3192e75b2d6a91c29",
"title": "102012bb5d7113b2da6d29a3192e75b2d6a91c29",
"url": "https://chromium.googlesource.com/infra/infra/+/102012bb5d7113b2da6d29a3192e75b2d6a91c29"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "9239caa383278f59629474cff80d94f8d43e6ead"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@9239caa383278f59629474cff80d94f8d43e6ead",
"title": "9239caa383278f59629474cff80d94f8d43e6ead",
"url": "https://chromium.googlesource.com/infra/infra/+/9239caa383278f59629474cff80d94f8d43e6ead"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "f67e37121fe9e7a4f2dd1b161db9300f6a28fdbb"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@f67e37121fe9e7a4f2dd1b161db9300f6a28fdbb",
"title": "f67e37121fe9e7a4f2dd1b161db9300f6a28fdbb",
"url": "https://chromium.googlesource.com/infra/infra/+/f67e37121fe9e7a4f2dd1b161db9300f6a28fdbb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "afc75d00ad2d75d89ec165f4a94f5f3451d9c41a"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@afc75d00ad2d75d89ec165f4a94f5f3451d9c41a",
"title": "afc75d00ad2d75d89ec165f4a94f5f3451d9c41a",
"url": "https://chromium.googlesource.com/infra/infra/+/afc75d00ad2d75d89ec165f4a94f5f3451d9c41a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "aef6088c84e523a04d28e30167bb32f30ed3703d"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@aef6088c84e523a04d28e30167bb32f30ed3703d",
"title": "aef6088c84e523a04d28e30167bb32f30ed3703d",
"url": "https://chromium.googlesource.com/infra/infra/+/aef6088c84e523a04d28e30167bb32f30ed3703d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "53e7e3a8a61da378aa2ee0c8b653d143e6b6f4b2"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@53e7e3a8a61da378aa2ee0c8b653d143e6b6f4b2",
"title": "53e7e3a8a61da378aa2ee0c8b653d143e6b6f4b2",
"url": "https://chromium.googlesource.com/infra/infra/+/53e7e3a8a61da378aa2ee0c8b653d143e6b6f4b2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "ee46b66fb03cf8356370d5f67c0a78283ff44159"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@ee46b66fb03cf8356370d5f67c0a78283ff44159",
"title": "ee46b66fb03cf8356370d5f67c0a78283ff44159",
"url": "https://chromium.googlesource.com/infra/infra/+/ee46b66fb03cf8356370d5f67c0a78283ff44159"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "ee46b66fb03cf8356370d5f67c0a78283ff44159",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8973062752071602048"
},
{
"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"
}
}
[21:18:57.979] Scheduled build:
{
"id": "8807632690132383489",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"number": 1489,
"createdBy": "project:infra",
"createTime": "2022-07-25T21:18:57.288321786Z",
"updateTime": "2022-07-25T21:18:57.288321786Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "ee46b66fb03cf8356370d5f67c0a78283ff44159",
"ref": "refs/heads/main"
}
}
}
[21:18:57.980] Task URL: https://cr-buildbucket-dev.appspot.com/build/8807632690132383489
[21:18:57.980] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:2:0) after 3m47s
[21:22:44.998] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:2:0)
[21:22:45.031] Build status: SCHEDULED
[21:22:45.031] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:3:0) after 2m41s
[21:25:26.169] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:3:0)
[21:25:26.201] Build status: SCHEDULED
[21:25:26.201] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:4:0) after 2m55s
[21:28:21.220] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:4:0)
[21:28:21.284] Build status: SCHEDULED
[21:28:21.284] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:5:0) after 9m34s
[21:37:55.311] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:5:0)
[21:37:55.370] Build status: SCHEDULED
[21:37:55.370] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:6:0) after 7m2s
[21:44:57.389] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:6:0)
[21:44:57.453] Build status: SCHEDULED
[21:44:57.453] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:7:0) after 4m17s
[21:49:14.472] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:7:0)
[21:49:14.503] Build status: SCHEDULED
[21:49:14.503] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:8:0) after 8m20s
[21:57:34.521] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:8:0)
[21:57:34.587] Build status: SCHEDULED
[21:57:34.587] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:9:0) after 2m40s
[22:00:14.607] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:9:0)
[22:00:14.646] Build status: SCHEDULED
[22:00:14.646] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:10:0) after 1m39s
[22:01:53.680] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:10:0)
[22:01:53.756] Build status: SCHEDULED
[22:01:53.756] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:11:0) after 8m52s
[22:10:45.777] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:11:0)
[22:10:45.865] Build status: SCHEDULED
[22:10:45.865] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:12:0) after 6m58s
[22:17:43.897] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:12:0)
[22:17:43.925] Build status: SCHEDULED
[22:17:43.925] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:13:0) after 2m8s
[22:19:51.947] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:13:0)
[22:19:51.989] Build status: SCHEDULED
[22:19:51.989] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:14:0) after 1m26s
[22:21:18.322] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:14:0)
[22:21:18.362] Build status: SCHEDULED
[22:21:18.362] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:15:0) after 5m58s
[22:27:16.384] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:15:0)
[22:27:16.425] Build status: SCHEDULED
[22:27:16.425] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:16:0) after 9m23s
[22:36:39.454] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:16:0)
[22:36:40.038] Build status: SCHEDULED
[22:36:40.038] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:17:0) after 9m19s
[22:45:59.083] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:17:0)
[22:45:59.159] Build status: SCHEDULED
[22:45:59.159] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:18:0) after 6m21s
[22:52:20.177] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:18:0)
[22:52:20.206] Build status: SCHEDULED
[22:52:20.206] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:19:0) after 8m50s
[23:01:10.241] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:19:0)
[23:01:10.293] Build status: SCHEDULED
[23:01:10.293] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:20:0) after 6m31s
[23:07:41.314] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:20:0)
[23:07:41.484] Build status: SCHEDULED
[23:07:41.485] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:21:0) after 4m47s
[23:12:28.651] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:21:0)
[23:12:28.671] Build status: SCHEDULED
[23:12:28.671] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:22:0) after 4m27s
[23:16:55.691] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:22:0)
[23:16:55.722] Build status: SCHEDULED
[23:16:55.722] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:23:0) after 9m37s
[23:26:32.744] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:23:0)
[23:26:32.828] Build status: SCHEDULED
[23:26:32.828] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:24:0) after 3m53s
[23:30:25.995] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:24:0)
[23:30:26.022] Build status: SCHEDULED
[23:30:26.022] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:25:0) after 1m56s
[23:32:22.097] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:25:0)
[23:32:22.163] Build status: SCHEDULED
[23:32:22.163] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:26:0) after 1m16s
[23:33:38.184] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:26:0)
[23:33:38.223] Build status: SCHEDULED
[23:33:38.223] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:27:0) after 6m19s
[23:39:57.244] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:27:0)
[23:39:57.298] Build status: SCHEDULED
[23:39:57.298] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:28:0) after 6m28s
[23:46:25.319] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:28:0)
[23:46:25.383] Build status: SCHEDULED
[23:46:25.383] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:29:0) after 1m29s
[23:47:54.403] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:29:0)
[23:47:54.458] Build status: SCHEDULED
[23:47:54.458] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:30:0) after 5m30s
[23:52:39.483] Received PubSub notification, asking Buildbucket for the build status
[23:52:39.534] Build status: STARTED
[23:53:24.480] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:30:0)
[23:53:24.517] Build status: STARTED
[23:53:24.518] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:32:0) after 2m3s
[23:55:27.548] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:32:0)
[23:55:27.572] Build status: STARTED
[23:55:27.572] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:33:0) after 6m11s
[00:01:38.595] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:33:0)
[00:01:38.617] Build status: STARTED
[00:01:38.617] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:34:0) after 5m50s
[00:07:28.640] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:34:0)
[00:07:28.690] Build status: STARTED
[00:07:28.690] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8973062752071602048:35:0) after 9m36s
[00:11:50.499] Received PubSub notification, asking Buildbucket for the build status
[00:11:50.531] Build:
{
"id": "8807632690132383489",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"number": 1489,
"createdBy": "project:infra",
"createTime": "2022-07-25T21:18:57.288321786Z",
"startTime": "2022-07-25T23:52:36.538351Z",
"endTime": "2022-07-26T00:11:50.277514396Z",
"updateTime": "2022-07-26T00:11:50.277514396Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "ee46b66fb03cf8356370d5f67c0a78283ff44159",
"ref": "refs/heads/main"
}
}
}
[00:11:50.531] Invocation finished in 2h52m54.476406047s with status FAILED