[02:12:10.097] New invocation is queued and will start shortly
[02:12:11.185] Starting the invocation (attempt 1)
[02:12:11.218] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06a1b6f11d390681fc22a89ef7e121e0be2331ed"
[02:12:11.218] Popped gitiles commit info from properties and tags
[02:12:11.218] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[02:12:11.220] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[02:12:11.221] Buildbucket request:
{
"requestId": "8956374461576724032",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8956374461576724032",
"job": "infra/infra-continuous-win11-64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "1530d5f7d8bba0ff17887c3d9c06d51b4fa42938"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@1530d5f7d8bba0ff17887c3d9c06d51b4fa42938",
"title": "1530d5f7d8bba0ff17887c3d9c06d51b4fa42938",
"url": "https://chromium.googlesource.com/infra/infra/+/1530d5f7d8bba0ff17887c3d9c06d51b4fa42938"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "91987c9dcb3196f53c9f32b13dae7c638b7f3150"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@91987c9dcb3196f53c9f32b13dae7c638b7f3150",
"title": "91987c9dcb3196f53c9f32b13dae7c638b7f3150",
"url": "https://chromium.googlesource.com/infra/infra/+/91987c9dcb3196f53c9f32b13dae7c638b7f3150"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "e92bc2eb046257f8c7007d299a8937af702268e3"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@e92bc2eb046257f8c7007d299a8937af702268e3",
"title": "e92bc2eb046257f8c7007d299a8937af702268e3",
"url": "https://chromium.googlesource.com/infra/infra/+/e92bc2eb046257f8c7007d299a8937af702268e3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "49bda1af701c0f2303be6eb3da0b2c4acdf12cbc"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@49bda1af701c0f2303be6eb3da0b2c4acdf12cbc",
"title": "49bda1af701c0f2303be6eb3da0b2c4acdf12cbc",
"url": "https://chromium.googlesource.com/infra/infra/+/49bda1af701c0f2303be6eb3da0b2c4acdf12cbc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "d514d7b47827ede556ad85d8a40c439ca941a2c7"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@d514d7b47827ede556ad85d8a40c439ca941a2c7",
"title": "d514d7b47827ede556ad85d8a40c439ca941a2c7",
"url": "https://chromium.googlesource.com/infra/infra/+/d514d7b47827ede556ad85d8a40c439ca941a2c7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "b16a68a551eb8bc633090d5f0505d0666d4d17af"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@b16a68a551eb8bc633090d5f0505d0666d4d17af",
"title": "b16a68a551eb8bc633090d5f0505d0666d4d17af",
"url": "https://chromium.googlesource.com/infra/infra/+/b16a68a551eb8bc633090d5f0505d0666d4d17af"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "3532a84ff13f695ba934916ac6bcabb135fc9411"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@3532a84ff13f695ba934916ac6bcabb135fc9411",
"title": "3532a84ff13f695ba934916ac6bcabb135fc9411",
"url": "https://chromium.googlesource.com/infra/infra/+/3532a84ff13f695ba934916ac6bcabb135fc9411"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "06a1b6f11d390681fc22a89ef7e121e0be2331ed"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@06a1b6f11d390681fc22a89ef7e121e0be2331ed",
"title": "06a1b6f11d390681fc22a89ef7e121e0be2331ed",
"url": "https://chromium.googlesource.com/infra/infra/+/06a1b6f11d390681fc22a89ef7e121e0be2331ed"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "06a1b6f11d390681fc22a89ef7e121e0be2331ed",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8956374461576724032"
},
{
"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:12:11.977] Scheduled build:
{
"id": "8790944399633659441",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"number": 2758,
"createdBy": "project:infra",
"createTime": "2023-01-26T02:12:11.322145207Z",
"updateTime": "2023-01-26T02:12:11.322145207Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "06a1b6f11d390681fc22a89ef7e121e0be2331ed",
"ref": "refs/heads/main"
}
}
}
[02:12:11.977] Task URL: https://cr-buildbucket-dev.appspot.com/build/8790944399633659441
[02:12:11.977] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:2:0) after 2m19s
[02:14:31.003] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:2:0)
[02:14:31.043] Build status: SCHEDULED
[02:14:31.044] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:3:0) after 6m5s
[02:20:36.109] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:3:0)
[02:20:36.215] Build status: SCHEDULED
[02:20:36.215] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:4:0) after 6m51s
[02:27:28.036] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:4:0)
[02:27:28.068] Build status: SCHEDULED
[02:27:28.068] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:5:0) after 2m48s
[02:30:16.104] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:5:0)
[02:30:16.187] Build status: SCHEDULED
[02:30:16.187] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:6:0) after 6m41s
[02:36:57.204] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:6:0)
[02:36:57.239] Build status: SCHEDULED
[02:36:57.239] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:7:0) after 6m56s
[02:43:53.262] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:7:0)
[02:43:53.314] Build status: SCHEDULED
[02:43:53.314] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:8:0) after 4m56s
[02:48:49.336] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:8:0)
[02:48:49.409] Build status: SCHEDULED
[02:48:49.409] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:9:0) after 1m4s
[02:49:53.429] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:9:0)
[02:49:53.477] Build status: SCHEDULED
[02:49:53.477] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:10:0) after 3m16s
[02:53:09.496] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:10:0)
[02:53:09.554] Build status: SCHEDULED
[02:53:09.554] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:11:0) after 7m58s
[03:01:07.621] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:11:0)
[03:01:07.690] Build status: SCHEDULED
[03:01:07.690] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:12:0) after 3m35s
[03:04:42.709] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:12:0)
[03:04:42.765] Build status: SCHEDULED
[03:04:42.765] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:13:0) after 4m24s
[03:09:06.782] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:13:0)
[03:09:06.834] Build status: SCHEDULED
[03:09:06.834] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:14:0) after 3m27s
[03:12:33.864] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:14:0)
[03:12:33.902] Build status: SCHEDULED
[03:12:33.903] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:15:0) after 5m33s
[03:18:06.924] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:15:0)
[03:18:06.955] Build status: SCHEDULED
[03:18:06.955] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:16:0) after 4m4s
[03:22:10.980] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:16:0)
[03:22:11.014] Build status: SCHEDULED
[03:22:11.014] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:17:0) after 6m30s
[03:28:41.040] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:17:0)
[03:28:41.076] Build status: SCHEDULED
[03:28:41.076] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:18:0) after 7m15s
[03:35:56.518] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:18:0)
[03:35:56.545] Build status: SCHEDULED
[03:35:56.545] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:19:0) after 1m47s
[03:37:43.568] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:19:0)
[03:37:43.605] Build status: SCHEDULED
[03:37:43.605] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:20:0) after 2m20s
[03:40:03.627] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:20:0)
[03:40:03.656] Build status: SCHEDULED
[03:40:03.656] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:21:0) after 5m19s
[03:45:22.686] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:21:0)
[03:45:22.734] Build status: SCHEDULED
[03:45:22.734] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:22:0) after 2m51s
[03:48:13.753] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:22:0)
[03:48:13.792] Build status: SCHEDULED
[03:48:13.792] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:23:0) after 2m24s
[03:50:37.829] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:23:0)
[03:50:37.861] Build status: SCHEDULED
[03:50:37.861] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:24:0) after 1m41s
[03:52:18.879] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:24:0)
[03:52:18.905] Build status: SCHEDULED
[03:52:18.905] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:25:0) after 9m12s
[04:01:30.926] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:25:0)
[04:01:30.970] Build status: SCHEDULED
[04:01:30.970] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:26:0) after 4m27s
[04:04:55.098] Received PubSub notification, asking Buildbucket for the build status
[04:04:55.138] Build status: STARTED
[04:05:57.994] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:26:0)
[04:05:58.019] Build status: STARTED
[04:05:58.019] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8956374461576724032:28:0) after 6m48s
[04:08:07.022] Received PubSub notification, asking Buildbucket for the build status
[04:08:07.048] Build:
{
"id": "8790944399633659441",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"number": 2758,
"createdBy": "project:infra",
"createTime": "2023-01-26T02:12:11.322145207Z",
"startTime": "2023-01-26T04:04:54.414916Z",
"endTime": "2023-01-26T04:08:06.629533543Z",
"updateTime": "2023-01-26T04:08:06.629533543Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "06a1b6f11d390681fc22a89ef7e121e0be2331ed",
"ref": "refs/heads/main"
}
}
}
[04:08:07.048] Invocation finished in 1h55m56.962656179s with status FAILED