[18:51:40.093] New invocation is queued and will start shortly
[18:51:41.175] Starting the invocation (attempt 1)
[18:51:41.216] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/f859196ae4aeaa5731a15534bc68fe5fc36506e7"
[18:51:41.216] Popped gitiles commit info from properties and tags
[18:51:41.216] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[18:51:41.216] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[18:51:41.216] Buildbucket request:
{
"requestId": "9004690358542135120",
"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/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "0adcda485ca24e195a7d6890197a4791b7e16698"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0adcda485ca24e195a7d6890197a4791b7e16698",
"title": "0adcda485ca24e195a7d6890197a4791b7e16698",
"url": "https://chromium.googlesource.com/infra/infra/+/0adcda485ca24e195a7d6890197a4791b7e16698"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "57057dcba01662d9c9252e999c312a4bcad21e1d"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@57057dcba01662d9c9252e999c312a4bcad21e1d",
"title": "57057dcba01662d9c9252e999c312a4bcad21e1d",
"url": "https://chromium.googlesource.com/infra/infra/+/57057dcba01662d9c9252e999c312a4bcad21e1d"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "2fe71719ce87d4ce868fe0cfd558484fa5043234"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@2fe71719ce87d4ce868fe0cfd558484fa5043234",
"title": "2fe71719ce87d4ce868fe0cfd558484fa5043234",
"url": "https://chromium.googlesource.com/infra/infra/+/2fe71719ce87d4ce868fe0cfd558484fa5043234"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "4bbc4a88b496f0f53b65f65c87c83b2897e03930"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4bbc4a88b496f0f53b65f65c87c83b2897e03930",
"title": "4bbc4a88b496f0f53b65f65c87c83b2897e03930",
"url": "https://chromium.googlesource.com/infra/infra/+/4bbc4a88b496f0f53b65f65c87c83b2897e03930"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "54177a582ea19f1943523b6f0093aa850c545fa1"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@54177a582ea19f1943523b6f0093aa850c545fa1",
"title": "54177a582ea19f1943523b6f0093aa850c545fa1",
"url": "https://chromium.googlesource.com/infra/infra/+/54177a582ea19f1943523b6f0093aa850c545fa1"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "f859196ae4aeaa5731a15534bc68fe5fc36506e7"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f859196ae4aeaa5731a15534bc68fe5fc36506e7",
"title": "f859196ae4aeaa5731a15534bc68fe5fc36506e7",
"url": "https://chromium.googlesource.com/infra/infra/+/f859196ae4aeaa5731a15534bc68fe5fc36506e7"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "f859196ae4aeaa5731a15534bc68fe5fc36506e7",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9004690358542135120"
},
{
"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"
}
}
[18:51:41.820] Scheduled build:
{
"id": "8839260296628439761",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 20357,
"createdBy": "project:infra",
"createTime": "2021-08-10T18:51:41.283633240Z",
"updateTime": "2021-08-10T18:51:41.616037417Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "f859196ae4aeaa5731a15534bc68fe5fc36506e7",
"ref": "refs/heads/master"
}
}
}
[18:51:41.820] Task URL: https://cr-buildbucket-dev.appspot.com/build/8839260296628439761
[18:51:41.820] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:2:0) after 2m23s
[18:54:04.842] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:2:0)
[18:54:04.875] Build status: SCHEDULED
[18:54:04.875] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:3:0) after 8m23s
[19:02:27.897] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:3:0)
[19:02:27.932] Build status: SCHEDULED
[19:02:27.932] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:4:0) after 3m56s
[19:06:23.959] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:4:0)
[19:06:24.004] Build status: SCHEDULED
[19:06:24.004] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:5:0) after 9m3s
[19:15:27.096] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:5:0)
[19:15:27.117] Build status: SCHEDULED
[19:15:27.117] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:6:0) after 3m28s
[19:18:55.138] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:6:0)
[19:18:55.166] Build status: SCHEDULED
[19:18:55.166] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:7:0) after 7m34s
[19:26:29.189] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:7:0)
[19:26:29.224] Build status: SCHEDULED
[19:26:29.224] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:8:0) after 4m35s
[19:31:04.256] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:8:0)
[19:31:04.296] Build status: SCHEDULED
[19:31:04.296] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:9:0) after 9m17s
[19:40:21.313] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:9:0)
[19:40:21.338] Build status: SCHEDULED
[19:40:21.338] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:10:0) after 6m27s
[19:46:48.362] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:10:0)
[19:46:48.406] Build status: SCHEDULED
[19:46:48.406] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:11:0) after 9m44s
[19:56:32.461] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:11:0)
[19:56:32.496] Build status: SCHEDULED
[19:56:32.496] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:12:0) after 7m51s
[20:04:23.517] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:12:0)
[20:04:23.557] Build status: SCHEDULED
[20:04:23.557] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:13:0) after 4m13s
[20:08:36.582] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:13:0)
[20:08:36.610] Build status: SCHEDULED
[20:08:36.610] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:14:0) after 2m54s
[20:11:30.631] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:14:0)
[20:11:30.653] Build status: SCHEDULED
[20:11:30.653] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:15:0) after 4m47s
[20:16:17.673] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:15:0)
[20:16:17.697] Build status: SCHEDULED
[20:16:17.698] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:16:0) after 2m47s
[20:19:04.716] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:16:0)
[20:19:04.753] Build status: SCHEDULED
[20:19:04.753] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:17:0) after 2m40s
[20:21:44.781] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:17:0)
[20:21:44.817] Build status: SCHEDULED
[20:21:44.817] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:18:0) after 8m34s
[20:30:18.838] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:18:0)
[20:30:18.876] Build status: SCHEDULED
[20:30:18.876] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:19:0) after 2m59s
[20:33:19.394] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:19:0)
[20:33:19.414] Build status: SCHEDULED
[20:33:19.414] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:20:0) after 6m29s
[20:39:48.437] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:20:0)
[20:39:48.474] Build status: SCHEDULED
[20:39:48.474] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:21:0) after 8m46s
[20:48:34.502] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:21:0)
[20:48:34.528] Build status: SCHEDULED
[20:48:34.529] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:22:0) after 1m34s
[20:50:08.551] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:22:0)
[20:50:08.578] Build status: SCHEDULED
[20:50:08.579] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9004690358542135120:23:0) after 9m33s
[20:52:22.641] Received PubSub notification, asking Buildbucket for the build status
[20:52:22.671] Build status: STARTED
[20:55:46.687] Received PubSub notification, asking Buildbucket for the build status
[20:55:46.714] Build:
{
"id": "8839260296628439761",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 20357,
"createdBy": "project:infra",
"createTime": "2021-08-10T18:51:41.283633240Z",
"startTime": "2021-08-10T20:52:20.182962Z",
"endTime": "2021-08-10T20:55:46.348182596Z",
"updateTime": "2021-08-10T20:55:46.348682266Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "f859196ae4aeaa5731a15534bc68fe5fc36506e7",
"ref": "refs/heads/master"
}
}
}
[20:55:46.714] Invocation finished in 2h4m6.639145079s with status FAILED