[00:07:48.130] New invocation is queued and will start shortly
[00:07:49.235] Starting the invocation (attempt 1)
[00:07:49.333] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/41a243e2c2306133a9b071c82eeb387336f0d3b9"
[00:07:49.333] Popped gitiles commit info from properties and tags
[00:07:49.333] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[00:07:49.333] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[00:07:49.333] Buildbucket request:
{
"requestId": "8984467345604784016",
"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": "df11154f5c86660b370348a34469131aa1cc9348"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@df11154f5c86660b370348a34469131aa1cc9348",
"title": "df11154f5c86660b370348a34469131aa1cc9348",
"url": "https://chromium.googlesource.com/infra/infra/+/df11154f5c86660b370348a34469131aa1cc9348"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "702f8ed4deed0e2d6e5bfb1f912f182abd13826d"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@702f8ed4deed0e2d6e5bfb1f912f182abd13826d",
"title": "702f8ed4deed0e2d6e5bfb1f912f182abd13826d",
"url": "https://chromium.googlesource.com/infra/infra/+/702f8ed4deed0e2d6e5bfb1f912f182abd13826d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "6ffcda00d626b41977189cff90fe2b23dcd4684a"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@6ffcda00d626b41977189cff90fe2b23dcd4684a",
"title": "6ffcda00d626b41977189cff90fe2b23dcd4684a",
"url": "https://chromium.googlesource.com/infra/infra/+/6ffcda00d626b41977189cff90fe2b23dcd4684a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "68d3da9fceb1dd3ccfb7f39b523b16521f7aeffd"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@68d3da9fceb1dd3ccfb7f39b523b16521f7aeffd",
"title": "68d3da9fceb1dd3ccfb7f39b523b16521f7aeffd",
"url": "https://chromium.googlesource.com/infra/infra/+/68d3da9fceb1dd3ccfb7f39b523b16521f7aeffd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "d79338341b4fffbc59cba7a5e485d257bb46782b"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@d79338341b4fffbc59cba7a5e485d257bb46782b",
"title": "d79338341b4fffbc59cba7a5e485d257bb46782b",
"url": "https://chromium.googlesource.com/infra/infra/+/d79338341b4fffbc59cba7a5e485d257bb46782b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "6d6c2f88f78e2a57344855599b7562644e24a74c"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@6d6c2f88f78e2a57344855599b7562644e24a74c",
"title": "6d6c2f88f78e2a57344855599b7562644e24a74c",
"url": "https://chromium.googlesource.com/infra/infra/+/6d6c2f88f78e2a57344855599b7562644e24a74c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "9dade54bd580b3047d0325d429f7729f0c63ede1"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@9dade54bd580b3047d0325d429f7729f0c63ede1",
"title": "9dade54bd580b3047d0325d429f7729f0c63ede1",
"url": "https://chromium.googlesource.com/infra/infra/+/9dade54bd580b3047d0325d429f7729f0c63ede1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "f527811a687a660a03aceed9efd229e11a56e0af"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@f527811a687a660a03aceed9efd229e11a56e0af",
"title": "f527811a687a660a03aceed9efd229e11a56e0af",
"url": "https://chromium.googlesource.com/infra/infra/+/f527811a687a660a03aceed9efd229e11a56e0af"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "636e9bf5b3f862edaffc55556af27d4c9cf10522"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@636e9bf5b3f862edaffc55556af27d4c9cf10522",
"title": "636e9bf5b3f862edaffc55556af27d4c9cf10522",
"url": "https://chromium.googlesource.com/infra/infra/+/636e9bf5b3f862edaffc55556af27d4c9cf10522"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "37df8a75d6b208aad300d4d501a5443d374d82dc"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@37df8a75d6b208aad300d4d501a5443d374d82dc",
"title": "37df8a75d6b208aad300d4d501a5443d374d82dc",
"url": "https://chromium.googlesource.com/infra/infra/+/37df8a75d6b208aad300d4d501a5443d374d82dc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "5b31c80b25f15b9711dae926c2938850b9e7104b"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@5b31c80b25f15b9711dae926c2938850b9e7104b",
"title": "5b31c80b25f15b9711dae926c2938850b9e7104b",
"url": "https://chromium.googlesource.com/infra/infra/+/5b31c80b25f15b9711dae926c2938850b9e7104b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "41a243e2c2306133a9b071c82eeb387336f0d3b9"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@41a243e2c2306133a9b071c82eeb387336f0d3b9",
"title": "41a243e2c2306133a9b071c82eeb387336f0d3b9",
"url": "https://chromium.googlesource.com/infra/infra/+/41a243e2c2306133a9b071c82eeb387336f0d3b9"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "41a243e2c2306133a9b071c82eeb387336f0d3b9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8984467345604784016"
},
{
"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"
}
}
[00:07:50.118] Scheduled build:
{
"id": "8819037283567128641",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 22089,
"createdBy": "project:infra",
"createTime": "2022-03-22T00:07:49.440973613Z",
"updateTime": "2022-03-22T00:07:49.440973613Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "41a243e2c2306133a9b071c82eeb387336f0d3b9",
"ref": "refs/heads/main"
}
}
}
[00:07:50.118] Task URL: https://cr-buildbucket-dev.appspot.com/build/8819037283567128641
[00:07:50.118] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:2:0) after 9m8s
[00:16:58.145] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:2:0)
[00:16:58.227] Build status: SCHEDULED
[00:16:58.227] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:3:0) after 7m35s
[00:24:33.246] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:3:0)
[00:24:33.301] Build status: SCHEDULED
[00:24:33.301] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:4:0) after 4m21s
[00:28:54.321] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:4:0)
[00:28:54.384] Build status: SCHEDULED
[00:28:54.384] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:5:0) after 9m55s
[00:38:49.408] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:5:0)
[00:38:49.459] Build status: SCHEDULED
[00:38:49.459] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:6:0) after 2m6s
[00:40:55.479] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:6:0)
[00:40:55.532] Build status: SCHEDULED
[00:40:55.532] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:7:0) after 8m21s
[00:49:16.555] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:7:0)
[00:49:16.599] Build status: SCHEDULED
[00:49:16.599] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:8:0) after 1m55s
[00:51:11.619] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:8:0)
[00:51:11.645] Build status: SCHEDULED
[00:51:11.645] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:9:0) after 8m59s
[01:00:10.674] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:9:0)
[01:00:10.729] Build status: SCHEDULED
[01:00:10.729] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:10:0) after 5m3s
[01:05:13.750] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:10:0)
[01:05:13.804] Build status: SCHEDULED
[01:05:13.804] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:11:0) after 9m3s
[01:14:16.827] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:11:0)
[01:14:16.876] Build status: SCHEDULED
[01:14:16.876] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:12:0) after 7m40s
[01:21:56.898] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:12:0)
[01:21:56.929] Build status: SCHEDULED
[01:21:56.929] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:13:0) after 1m49s
[01:23:45.966] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:13:0)
[01:23:45.994] Build status: SCHEDULED
[01:23:45.994] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:14:0) after 6m43s
[01:30:29.017] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:14:0)
[01:30:29.045] Build status: SCHEDULED
[01:30:29.045] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:15:0) after 2m52s
[01:33:21.076] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:15:0)
[01:33:21.117] Build status: SCHEDULED
[01:33:21.117] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8984467345604784016:16:0) after 5m37s
[01:37:58.480] Received PubSub notification, asking Buildbucket for the build status
[01:37:58.516] Build status: STARTED
[01:38:03.823] Received PubSub notification, asking Buildbucket for the build status
[01:38:03.844] Build:
{
"id": "8819037283567128641",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 22089,
"createdBy": "project:infra",
"createTime": "2022-03-22T00:07:49.440973613Z",
"startTime": "2022-03-22T01:37:56.508097Z",
"endTime": "2022-03-22T01:38:03.447890660Z",
"updateTime": "2022-03-22T01:38:03.447890660Z",
"status": "INFRA_FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "41a243e2c2306133a9b071c82eeb387336f0d3b9",
"ref": "refs/heads/main"
}
}
}
[01:38:03.844] Invocation finished in 1h30m15.730759321s with status FAILED