[02:55:38.076] New invocation is queued and will start shortly
[02:55:39.162] Starting the invocation (attempt 1)
[02:55:39.203] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/24daf20203b8cff2e0fcc219e49038305e19b175"
[02:55:39.203] Popped gitiles commit info from properties and tags
[02:55:39.203] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[02:55:39.204] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[02:55:39.204] Buildbucket request:
{
"requestId": "8984366189534813088",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-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": "d5445565599212abf032812c02287adead53d687"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@d5445565599212abf032812c02287adead53d687",
"title": "d5445565599212abf032812c02287adead53d687",
"url": "https://chromium.googlesource.com/infra/infra/+/d5445565599212abf032812c02287adead53d687"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "364a75bb6ace5ee6dc25f4324eaec044a33d80c4"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@364a75bb6ace5ee6dc25f4324eaec044a33d80c4",
"title": "364a75bb6ace5ee6dc25f4324eaec044a33d80c4",
"url": "https://chromium.googlesource.com/infra/infra/+/364a75bb6ace5ee6dc25f4324eaec044a33d80c4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "0888a2a0fc059878e082e6703e0baf25ebde6fc3"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@0888a2a0fc059878e082e6703e0baf25ebde6fc3",
"title": "0888a2a0fc059878e082e6703e0baf25ebde6fc3",
"url": "https://chromium.googlesource.com/infra/infra/+/0888a2a0fc059878e082e6703e0baf25ebde6fc3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "eb9c585cee46d13522bf63c019f722069985cff2"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@eb9c585cee46d13522bf63c019f722069985cff2",
"title": "eb9c585cee46d13522bf63c019f722069985cff2",
"url": "https://chromium.googlesource.com/infra/infra/+/eb9c585cee46d13522bf63c019f722069985cff2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "6aed23738d813b98973957c3565f445ea0f4d0ee"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@6aed23738d813b98973957c3565f445ea0f4d0ee",
"title": "6aed23738d813b98973957c3565f445ea0f4d0ee",
"url": "https://chromium.googlesource.com/infra/infra/+/6aed23738d813b98973957c3565f445ea0f4d0ee"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "1555e3127edbfcea10460935f94dbbaf6849900d"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@1555e3127edbfcea10460935f94dbbaf6849900d",
"title": "1555e3127edbfcea10460935f94dbbaf6849900d",
"url": "https://chromium.googlesource.com/infra/infra/+/1555e3127edbfcea10460935f94dbbaf6849900d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "bef478fa3bc4b27d28443baa418a3cefbf06e479"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@bef478fa3bc4b27d28443baa418a3cefbf06e479",
"title": "bef478fa3bc4b27d28443baa418a3cefbf06e479",
"url": "https://chromium.googlesource.com/infra/infra/+/bef478fa3bc4b27d28443baa418a3cefbf06e479"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "24daf20203b8cff2e0fcc219e49038305e19b175"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@24daf20203b8cff2e0fcc219e49038305e19b175",
"title": "24daf20203b8cff2e0fcc219e49038305e19b175",
"url": "https://chromium.googlesource.com/infra/infra/+/24daf20203b8cff2e0fcc219e49038305e19b175"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "24daf20203b8cff2e0fcc219e49038305e19b175",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8984366189534813088"
},
{
"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"
}
}
[02:55:40.333] Scheduled build:
{
"id": "8818936127507269457",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 22118,
"createdBy": "project:infra",
"createTime": "2022-03-23T02:55:39.377086671Z",
"updateTime": "2022-03-23T02:55:39.377086671Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "24daf20203b8cff2e0fcc219e49038305e19b175",
"ref": "refs/heads/main"
}
}
}
[02:55:40.333] Task URL: https://cr-buildbucket-dev.appspot.com/build/8818936127507269457
[02:55:40.333] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:2:0) after 8m2s
[03:03:42.351] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:2:0)
[03:03:42.403] Build status: SCHEDULED
[03:03:42.403] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:3:0) after 2m55s
[03:06:37.420] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:3:0)
[03:06:37.901] Build status: SCHEDULED
[03:06:37.901] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:4:0) after 8m52s
[03:15:29.921] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:4:0)
[03:15:29.944] Build status: SCHEDULED
[03:15:29.945] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:5:0) after 3m4s
[03:18:33.967] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:5:0)
[03:18:34.046] Build status: SCHEDULED
[03:18:34.046] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:6:0) after 2m7s
[03:20:41.064] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:6:0)
[03:20:41.131] Build status: SCHEDULED
[03:20:41.131] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:7:0) after 3m4s
[03:23:45.155] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:7:0)
[03:23:45.187] Build status: SCHEDULED
[03:23:45.187] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:8:0) after 6m30s
[03:30:15.212] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:8:0)
[03:30:15.238] Build status: SCHEDULED
[03:30:15.238] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:9:0) after 1m38s
[03:31:53.256] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:9:0)
[03:31:53.320] Build status: SCHEDULED
[03:31:53.320] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:10:0) after 9m27s
[03:41:20.355] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:10:0)
[03:41:20.420] Build status: SCHEDULED
[03:41:20.420] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:11:0) after 7m9s
[03:48:29.441] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:11:0)
[03:48:29.468] Build status: SCHEDULED
[03:48:29.468] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:12:0) after 9m11s
[03:57:40.498] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:12:0)
[03:57:40.579] Build status: SCHEDULED
[03:57:40.579] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:13:0) after 4m45s
[04:02:25.606] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:13:0)
[04:02:25.632] Build status: SCHEDULED
[04:02:25.632] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:14:0) after 8m50s
[04:11:15.658] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:14:0)
[04:11:15.715] Build status: SCHEDULED
[04:11:15.715] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:15:0) after 2m44s
[04:13:59.735] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:15:0)
[04:13:59.825] Build status: SCHEDULED
[04:13:59.825] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:16:0) after 4m12s
[04:18:11.855] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:16:0)
[04:18:11.914] Build status: SCHEDULED
[04:18:11.914] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:17:0) after 7m18s
[04:25:29.939] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:17:0)
[04:25:30.030] Build status: SCHEDULED
[04:25:30.030] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:18:0) after 3m23s
[04:28:53.051] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:18:0)
[04:28:53.178] Build status: SCHEDULED
[04:28:53.178] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:19:0) after 5m59s
[04:34:52.172] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:19:0)
[04:34:52.224] Build status: SCHEDULED
[04:34:52.224] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:20:0) after 6m9s
[04:41:01.280] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:20:0)
[04:41:01.329] Build status: SCHEDULED
[04:41:01.329] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:21:0) after 6m25s
[04:47:26.356] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:21:0)
[04:47:26.400] Build status: SCHEDULED
[04:47:26.400] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:22:0) after 7m45s
[04:55:11.427] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:22:0)
[04:55:11.470] Build status: SCHEDULED
[04:55:11.470] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:23:0) after 2m39s
[04:57:50.497] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:23:0)
[04:57:50.635] Build status: SCHEDULED
[04:57:50.635] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:24:0) after 1m33s
[04:59:23.656] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:24:0)
[04:59:23.817] Build status: SCHEDULED
[04:59:23.817] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:25:0) after 2m10s
[05:01:33.844] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:25:0)
[05:01:33.982] Build status: SCHEDULED
[05:01:33.982] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:26:0) after 3m57s
[05:05:31.018] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:26:0)
[05:05:31.062] Build status: SCHEDULED
[05:05:31.062] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:27:0) after 8m40s
[05:14:11.084] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:27:0)
[05:14:11.125] Build status: SCHEDULED
[05:14:11.125] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:28:0) after 2m24s
[05:16:35.154] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:28:0)
[05:16:35.212] Build status: SCHEDULED
[05:16:35.212] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:29:0) after 2m21s
[05:18:56.231] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:29:0)
[05:18:56.317] Build status: SCHEDULED
[05:18:56.317] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:30:0) after 4m54s
[05:23:50.342] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:30:0)
[05:23:50.406] Build status: SCHEDULED
[05:23:50.406] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:31:0) after 1m27s
[05:25:17.430] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:31:0)
[05:25:17.477] Build status: SCHEDULED
[05:25:17.477] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:32:0) after 9m53s
[05:35:10.541] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:32:0)
[05:35:10.588] Build status: SCHEDULED
[05:35:10.588] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:33:0) after 4m37s
[05:39:47.609] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:33:0)
[05:39:47.639] Build status: SCHEDULED
[05:39:47.639] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:34:0) after 1m44s
[05:41:31.664] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:34:0)
[05:41:31.709] Build status: SCHEDULED
[05:41:31.709] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:35:0) after 9m25s
[05:50:56.728] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:35:0)
[05:50:56.769] Build status: SCHEDULED
[05:50:56.769] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:36:0) after 6m36s
[05:57:32.791] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:36:0)
[05:57:32.825] Build status: SCHEDULED
[05:57:32.825] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:37:0) after 5m56s
[06:03:28.858] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:37:0)
[06:03:28.886] Build status: SCHEDULED
[06:03:28.886] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:38:0) after 8m58s
[06:12:26.906] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:38:0)
[06:12:26.965] Build status: SCHEDULED
[06:12:26.965] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:39:0) after 5m58s
[06:18:24.985] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:39:0)
[06:18:25.023] Build status: SCHEDULED
[06:18:25.023] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:40:0) after 8m10s
[06:26:35.044] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:40:0)
[06:26:35.072] Build status: SCHEDULED
[06:26:35.072] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:41:0) after 9m6s
[06:35:41.095] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:41:0)
[06:35:41.130] Build status: SCHEDULED
[06:35:41.130] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:42:0) after 7m33s
[06:43:14.153] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:42:0)
[06:43:14.193] Build status: SCHEDULED
[06:43:14.193] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:43:0) after 1m39s
[06:44:53.212] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:43:0)
[06:44:53.246] Build status: SCHEDULED
[06:44:53.246] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:44:0) after 6m23s
[06:51:16.272] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:44:0)
[06:51:16.310] Build status: SCHEDULED
[06:51:16.310] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:45:0) after 8m42s
[06:59:58.337] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:45:0)
[06:59:58.411] Build status: SCHEDULED
[06:59:58.411] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:46:0) after 8m41s
[07:03:41.560] Received PubSub notification, asking Buildbucket for the build status
[07:03:41.604] Build status: STARTED
[07:08:39.431] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:46:0)
[07:08:39.489] Build status: STARTED
[07:08:39.489] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:48:0) after 3m7s
[07:11:46.511] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:48:0)
[07:11:46.574] Build status: STARTED
[07:11:46.574] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:49:0) after 4m32s
[07:16:18.599] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:49:0)
[07:16:18.646] Build status: STARTED
[07:16:18.646] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:50:0) after 1m57s
[07:18:15.676] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:50:0)
[07:18:15.703] Build status: STARTED
[07:18:15.703] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984366189534813088:51:0) after 4m58s
[07:19:51.465] Received PubSub notification, asking Buildbucket for the build status
[07:19:51.511] Build:
{
"id": "8818936127507269457",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 22118,
"createdBy": "project:infra",
"createTime": "2022-03-23T02:55:39.377086671Z",
"startTime": "2022-03-23T07:03:39.673346Z",
"endTime": "2022-03-23T07:19:49.101789857Z",
"updateTime": "2022-03-23T07:19:49.101789857Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "24daf20203b8cff2e0fcc219e49038305e19b175",
"ref": "refs/heads/main"
}
}
}
[07:19:51.511] Invocation finished in 4h24m13.451585569s with status FAILED