[21:05:50.069] New invocation is queued and will start shortly
[21:05:51.155] Starting the invocation (attempt 1)
[21:05:51.203] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/cc9bdf42d5bfc7b8651845713b318cc951ee7383"
[21:05:51.203] Popped gitiles commit info from properties and tags
[21:05:51.203] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[21:05:51.222] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[21:05:51.223] Buildbucket request:
{
"requestId": "8988827448407125392",
"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": "8e315401116bbc5f80ed0063020a73aea2b1cacc"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@8e315401116bbc5f80ed0063020a73aea2b1cacc",
"title": "8e315401116bbc5f80ed0063020a73aea2b1cacc",
"url": "https://chromium.googlesource.com/infra/infra/+/8e315401116bbc5f80ed0063020a73aea2b1cacc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "b183bbc0999645130d5e9f33ce33804e7663947e"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@b183bbc0999645130d5e9f33ce33804e7663947e",
"title": "b183bbc0999645130d5e9f33ce33804e7663947e",
"url": "https://chromium.googlesource.com/infra/infra/+/b183bbc0999645130d5e9f33ce33804e7663947e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "767a570a427ed8ac10118dd2df180b3e1bb4fa14"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@767a570a427ed8ac10118dd2df180b3e1bb4fa14",
"title": "767a570a427ed8ac10118dd2df180b3e1bb4fa14",
"url": "https://chromium.googlesource.com/infra/infra/+/767a570a427ed8ac10118dd2df180b3e1bb4fa14"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "a31e4ff765e1833e19748da8b445d3c409c547f2"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@a31e4ff765e1833e19748da8b445d3c409c547f2",
"title": "a31e4ff765e1833e19748da8b445d3c409c547f2",
"url": "https://chromium.googlesource.com/infra/infra/+/a31e4ff765e1833e19748da8b445d3c409c547f2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "d19b93955d85cad554379fceedc0e4659c1a1af3"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@d19b93955d85cad554379fceedc0e4659c1a1af3",
"title": "d19b93955d85cad554379fceedc0e4659c1a1af3",
"url": "https://chromium.googlesource.com/infra/infra/+/d19b93955d85cad554379fceedc0e4659c1a1af3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "cc9bdf42d5bfc7b8651845713b318cc951ee7383"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@cc9bdf42d5bfc7b8651845713b318cc951ee7383",
"title": "cc9bdf42d5bfc7b8651845713b318cc951ee7383",
"url": "https://chromium.googlesource.com/infra/infra/+/cc9bdf42d5bfc7b8651845713b318cc951ee7383"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "cc9bdf42d5bfc7b8651845713b318cc951ee7383",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8988827448407125392"
},
{
"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"
}
}
[21:05:52.269] Scheduled build:
{
"id": "8823397386359911537",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"number": 304,
"createdBy": "project:infra",
"createTime": "2022-02-01T21:05:51.390248168Z",
"updateTime": "2022-02-01T21:05:51.390248168Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "cc9bdf42d5bfc7b8651845713b318cc951ee7383",
"ref": "refs/heads/main"
}
}
}
[21:05:52.269] Task URL: https://cr-buildbucket-dev.appspot.com/build/8823397386359911537
[21:05:52.269] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:2:0) after 8m57s
[21:14:49.287] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:2:0)
[21:14:49.333] Build status: SCHEDULED
[21:14:49.333] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:3:0) after 8m36s
[21:23:25.355] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:3:0)
[21:23:25.414] Build status: SCHEDULED
[21:23:25.414] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:4:0) after 8m52s
[21:32:17.443] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:4:0)
[21:32:17.567] Build status: SCHEDULED
[21:32:17.567] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:5:0) after 8m42s
[21:40:59.588] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:5:0)
[21:40:59.627] Build status: SCHEDULED
[21:40:59.627] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:6:0) after 2m47s
[21:43:46.651] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:6:0)
[21:43:46.713] Build status: SCHEDULED
[21:43:46.714] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:7:0) after 6m16s
[21:50:02.747] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:7:0)
[21:50:02.813] Build status: SCHEDULED
[21:50:02.813] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:8:0) after 9m58s
[22:00:00.835] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:8:0)
[22:00:00.868] Build status: SCHEDULED
[22:00:00.868] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:9:0) after 2m23s
[22:02:23.891] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:9:0)
[22:02:23.930] Build status: SCHEDULED
[22:02:23.930] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:10:0) after 3m51s
[22:06:14.955] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:10:0)
[22:06:14.997] Build status: SCHEDULED
[22:06:14.997] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:11:0) after 1m26s
[22:07:41.022] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:11:0)
[22:07:41.062] Build status: SCHEDULED
[22:07:41.062] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:12:0) after 5m43s
[22:13:24.086] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:12:0)
[22:13:24.151] Build status: SCHEDULED
[22:13:24.151] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:13:0) after 3m42s
[22:17:06.174] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:13:0)
[22:17:06.212] Build status: SCHEDULED
[22:17:06.212] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:14:0) after 3m45s
[22:20:51.235] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:14:0)
[22:20:51.267] Build status: SCHEDULED
[22:20:51.267] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:15:0) after 3m29s
[22:24:20.286] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:15:0)
[22:24:20.311] Build status: SCHEDULED
[22:24:20.311] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:16:0) after 6m48s
[22:31:08.332] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:16:0)
[22:31:08.369] Build status: SCHEDULED
[22:31:08.369] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:17:0) after 9m25s
[22:40:33.394] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:17:0)
[22:40:33.433] Build status: SCHEDULED
[22:40:33.433] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:18:0) after 1m53s
[22:42:26.458] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:18:0)
[22:42:26.494] Build status: SCHEDULED
[22:42:26.494] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:19:0) after 6m49s
[22:49:15.534] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:19:0)
[22:49:15.557] Build status: SCHEDULED
[22:49:15.557] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:20:0) after 4m16s
[22:53:31.580] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:20:0)
[22:53:31.620] Build status: SCHEDULED
[22:53:31.620] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:21:0) after 7m41s
[23:01:12.643] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:21:0)
[23:01:12.678] Build status: SCHEDULED
[23:01:12.678] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:22:0) after 7m7s
[23:08:19.699] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:22:0)
[23:08:19.725] Build status: SCHEDULED
[23:08:19.725] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:23:0) after 1m24s
[23:09:43.753] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:23:0)
[23:09:43.821] Build status: SCHEDULED
[23:09:43.821] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:24:0) after 8m56s
[23:11:27.742] Received PubSub notification, asking Buildbucket for the build status
[23:11:27.816] Build status: STARTED
[23:18:39.845] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:24:0)
[23:18:39.900] Build status: STARTED
[23:18:39.900] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:26:0) after 9m56s
[23:28:35.923] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:26:0)
[23:28:35.976] Build status: STARTED
[23:28:35.976] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:27:0) after 3m47s
[23:32:23.005] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:27:0)
[23:32:23.043] Build status: STARTED
[23:32:23.043] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:28:0) after 3m16s
[23:35:39.065] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:28:0)
[23:35:39.089] Build status: STARTED
[23:35:39.089] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:29:0) after 4m30s
[23:40:09.117] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:29:0)
[23:40:09.164] Build status: STARTED
[23:40:09.164] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8988827448407125392:30:0) after 9m9s
[23:43:15.121] Received PubSub notification, asking Buildbucket for the build status
[23:43:15.155] Build:
{
"id": "8823397386359911537",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"number": 304,
"createdBy": "project:infra",
"createTime": "2022-02-01T21:05:51.390248168Z",
"startTime": "2022-02-01T23:11:25.946235Z",
"endTime": "2022-02-01T23:43:07.873549168Z",
"updateTime": "2022-02-01T23:43:07.873549168Z",
"status": "INFRA_FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "cc9bdf42d5bfc7b8651845713b318cc951ee7383",
"ref": "refs/heads/main"
}
}
}
[23:43:15.155] Invocation finished in 2h37m25.101864301s with status FAILED