[02:44:40.092] New invocation is queued and will start shortly
[02:44:41.181] Starting the invocation (attempt 1)
[02:44:41.221] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/c04738152750fec4fc83da627cfebfea06a2483a"
[02:44:41.221] Popped gitiles commit info from properties and tags
[02:44:41.221] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[02:44:41.221] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[02:44:41.222] Buildbucket request:
{
"requestId": "8986812997573553200",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "dea63ddd34375cd4fef8d143a92dfff7501ae7aa"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@dea63ddd34375cd4fef8d143a92dfff7501ae7aa",
"title": "dea63ddd34375cd4fef8d143a92dfff7501ae7aa",
"url": "https://chromium.googlesource.com/infra/infra/+/dea63ddd34375cd4fef8d143a92dfff7501ae7aa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "cef75800b78966f8d3daef1943f0726a807fcbd6"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@cef75800b78966f8d3daef1943f0726a807fcbd6",
"title": "cef75800b78966f8d3daef1943f0726a807fcbd6",
"url": "https://chromium.googlesource.com/infra/infra/+/cef75800b78966f8d3daef1943f0726a807fcbd6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "3d8c468ad545f7978d47d150d5f9aca91b74ed3a"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@3d8c468ad545f7978d47d150d5f9aca91b74ed3a",
"title": "3d8c468ad545f7978d47d150d5f9aca91b74ed3a",
"url": "https://chromium.googlesource.com/infra/infra/+/3d8c468ad545f7978d47d150d5f9aca91b74ed3a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "a2bcc11b95de481461533db2c0b19ddc2296ee27"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@a2bcc11b95de481461533db2c0b19ddc2296ee27",
"title": "a2bcc11b95de481461533db2c0b19ddc2296ee27",
"url": "https://chromium.googlesource.com/infra/infra/+/a2bcc11b95de481461533db2c0b19ddc2296ee27"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "5c62f18c7e69ec904559405110f651c4935b0a84"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@5c62f18c7e69ec904559405110f651c4935b0a84",
"title": "5c62f18c7e69ec904559405110f651c4935b0a84",
"url": "https://chromium.googlesource.com/infra/infra/+/5c62f18c7e69ec904559405110f651c4935b0a84"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "3ecfb13708be7f5d119356fd3b8ed5c378e755f2"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@3ecfb13708be7f5d119356fd3b8ed5c378e755f2",
"title": "3ecfb13708be7f5d119356fd3b8ed5c378e755f2",
"url": "https://chromium.googlesource.com/infra/infra/+/3ecfb13708be7f5d119356fd3b8ed5c378e755f2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "38a047b605dbeab2e0f246779e3276190f60ef27"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@38a047b605dbeab2e0f246779e3276190f60ef27",
"title": "38a047b605dbeab2e0f246779e3276190f60ef27",
"url": "https://chromium.googlesource.com/infra/infra/+/38a047b605dbeab2e0f246779e3276190f60ef27"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "9e20b536d01911281016956359bc1ece105b58ac"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@9e20b536d01911281016956359bc1ece105b58ac",
"title": "9e20b536d01911281016956359bc1ece105b58ac",
"url": "https://chromium.googlesource.com/infra/infra/+/9e20b536d01911281016956359bc1ece105b58ac"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "40a55b4edf19d51141764151098701a4ba8b6e40"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@40a55b4edf19d51141764151098701a4ba8b6e40",
"title": "40a55b4edf19d51141764151098701a4ba8b6e40",
"url": "https://chromium.googlesource.com/infra/infra/+/40a55b4edf19d51141764151098701a4ba8b6e40"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "c9daec0f736fd67518ea54c68da2b121a45f39dd"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@c9daec0f736fd67518ea54c68da2b121a45f39dd",
"title": "c9daec0f736fd67518ea54c68da2b121a45f39dd",
"url": "https://chromium.googlesource.com/infra/infra/+/c9daec0f736fd67518ea54c68da2b121a45f39dd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "fed1ef37acf647f1d55cd98bf1145c81dcbe458d"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@fed1ef37acf647f1d55cd98bf1145c81dcbe458d",
"title": "fed1ef37acf647f1d55cd98bf1145c81dcbe458d",
"url": "https://chromium.googlesource.com/infra/infra/+/fed1ef37acf647f1d55cd98bf1145c81dcbe458d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "06698798c3d05e0c661e024a65348993092a3528"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@06698798c3d05e0c661e024a65348993092a3528",
"title": "06698798c3d05e0c661e024a65348993092a3528",
"url": "https://chromium.googlesource.com/infra/infra/+/06698798c3d05e0c661e024a65348993092a3528"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "722fa9b7d61f32ee401b23ed97c1863e3fffd3f9"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@722fa9b7d61f32ee401b23ed97c1863e3fffd3f9",
"title": "722fa9b7d61f32ee401b23ed97c1863e3fffd3f9",
"url": "https://chromium.googlesource.com/infra/infra/+/722fa9b7d61f32ee401b23ed97c1863e3fffd3f9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "d922c9eb8b79779e29faf8b2b36d6921dc71a7b2"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@d922c9eb8b79779e29faf8b2b36d6921dc71a7b2",
"title": "d922c9eb8b79779e29faf8b2b36d6921dc71a7b2",
"url": "https://chromium.googlesource.com/infra/infra/+/d922c9eb8b79779e29faf8b2b36d6921dc71a7b2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "c04738152750fec4fc83da627cfebfea06a2483a"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@c04738152750fec4fc83da627cfebfea06a2483a",
"title": "c04738152750fec4fc83da627cfebfea06a2483a",
"url": "https://chromium.googlesource.com/infra/infra/+/c04738152750fec4fc83da627cfebfea06a2483a"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "c04738152750fec4fc83da627cfebfea06a2483a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8986812997573553200"
},
{
"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"
}
}
[02:44:42.142] Scheduled build:
{
"id": "8821382935653231249",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"number": 456,
"createdBy": "project:infra",
"createTime": "2022-02-24T02:44:41.291864311Z",
"updateTime": "2022-02-24T02:44:41.291864311Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "c04738152750fec4fc83da627cfebfea06a2483a",
"ref": "refs/heads/main"
}
}
}
[02:44:42.142] Task URL: https://cr-buildbucket-dev.appspot.com/build/8821382935653231249
[02:44:42.142] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:2:0) after 9m41s
[02:54:23.162] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:2:0)
[02:54:23.198] Build status: SCHEDULED
[02:54:23.198] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:3:0) after 2m15s
[02:56:38.216] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:3:0)
[02:56:38.253] Build status: SCHEDULED
[02:56:38.254] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:4:0) after 4m32s
[03:01:10.275] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:4:0)
[03:01:10.307] Build status: SCHEDULED
[03:01:10.307] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:5:0) after 2m58s
[03:04:08.326] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:5:0)
[03:04:08.363] Build status: SCHEDULED
[03:04:08.363] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:6:0) after 9m41s
[03:13:49.382] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:6:0)
[03:13:49.403] Build status: SCHEDULED
[03:13:49.403] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:7:0) after 7m16s
[03:21:05.429] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:7:0)
[03:21:05.471] Build status: SCHEDULED
[03:21:05.471] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:8:0) after 7m43s
[03:28:48.496] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:8:0)
[03:28:48.531] Build status: SCHEDULED
[03:28:48.531] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:9:0) after 6m12s
[03:35:00.549] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:9:0)
[03:35:00.585] Build status: SCHEDULED
[03:35:00.585] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:10:0) after 7m24s
[03:42:24.612] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:10:0)
[03:42:24.667] Build status: SCHEDULED
[03:42:24.667] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:11:0) after 6m28s
[03:48:52.693] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:11:0)
[03:48:52.740] Build status: SCHEDULED
[03:48:52.740] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:12:0) after 7m24s
[03:56:16.765] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:12:0)
[03:56:16.813] Build status: SCHEDULED
[03:56:16.813] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:13:0) after 6m25s
[04:02:41.848] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:13:0)
[04:02:41.881] Build status: SCHEDULED
[04:02:41.881] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:14:0) after 9m1s
[04:11:42.900] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:14:0)
[04:11:42.920] Build status: SCHEDULED
[04:11:42.920] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:15:0) after 2m25s
[04:14:07.945] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:15:0)
[04:14:07.974] Build status: SCHEDULED
[04:14:07.974] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:16:0) after 8m51s
[04:22:58.995] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:16:0)
[04:22:59.026] Build status: SCHEDULED
[04:22:59.026] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:17:0) after 5m57s
[04:28:56.061] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:17:0)
[04:28:56.099] Build status: SCHEDULED
[04:28:56.099] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:18:0) after 6m54s
[04:35:50.117] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:18:0)
[04:35:50.156] Build status: SCHEDULED
[04:35:50.156] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:19:0) after 1m38s
[04:37:28.179] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:19:0)
[04:37:28.210] Build status: SCHEDULED
[04:37:28.210] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:20:0) after 9m3s
[04:46:31.243] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:20:0)
[04:46:31.300] Build status: SCHEDULED
[04:46:31.300] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:21:0) after 8m53s
[04:55:24.325] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:21:0)
[04:55:24.348] Build status: SCHEDULED
[04:55:24.348] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:22:0) after 9m55s
[05:00:12.662] Received PubSub notification, asking Buildbucket for the build status
[05:00:12.681] Build status: STARTED
[05:05:19.373] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:22:0)
[05:05:19.421] Build status: STARTED
[05:05:19.421] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:24:0) after 6m43s
[05:12:02.453] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:24:0)
[05:12:02.496] Build status: STARTED
[05:12:02.496] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:25:0) after 2m1s
[05:14:03.529] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:25:0)
[05:14:03.568] Build status: STARTED
[05:14:03.568] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:26:0) after 2m13s
[05:16:16.590] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:26:0)
[05:16:16.635] Build status: STARTED
[05:16:16.635] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:27:0) after 8m50s
[05:25:06.672] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:27:0)
[05:25:06.698] Build status: STARTED
[05:25:06.698] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:28:0) after 3m32s
[05:28:38.775] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:28:0)
[05:28:38.815] Build status: STARTED
[05:28:38.815] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986812997573553200:29:0) after 7m46s
[05:32:27.441] Received PubSub notification, asking Buildbucket for the build status
[05:32:27.462] Build:
{
"id": "8821382935653231249",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"number": 456,
"createdBy": "project:infra",
"createTime": "2022-02-24T02:44:41.291864311Z",
"startTime": "2022-02-24T05:00:10.737653Z",
"endTime": "2022-02-24T05:32:23.391177Z",
"updateTime": "2022-02-24T05:32:23.866939Z",
"status": "INFRA_FAILURE",
"statusDetails": {
"timeout": {
}
},
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "c04738152750fec4fc83da627cfebfea06a2483a",
"ref": "refs/heads/main"
}
}
}
[05:32:27.462] Invocation finished in 2h47m47.385756544s with status FAILED