[12:27:34.056] New invocation is queued and will start shortly
[12:27:35.121] Starting the invocation (attempt 1)
[12:27:35.151] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/307b57b2fc6688a8a98f15b6c2ff7b9599abc1b0"
[12:27:35.151] Popped gitiles commit info from properties and tags
[12:27:35.151] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[12:27:35.151] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[12:27:35.151] Buildbucket request:
{
"requestId": "8903698906518489824",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8903698906518489824",
"job": "infra/infra-continuous-win11-64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "43db9ac972a4dec8c54b0768e90980c9f218e05d"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@43db9ac972a4dec8c54b0768e90980c9f218e05d",
"title": "43db9ac972a4dec8c54b0768e90980c9f218e05d",
"url": "https://chromium.googlesource.com/infra/infra/+/43db9ac972a4dec8c54b0768e90980c9f218e05d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "307b57b2fc6688a8a98f15b6c2ff7b9599abc1b0"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@307b57b2fc6688a8a98f15b6c2ff7b9599abc1b0",
"title": "307b57b2fc6688a8a98f15b6c2ff7b9599abc1b0",
"url": "https://chromium.googlesource.com/infra/infra/+/307b57b2fc6688a8a98f15b6c2ff7b9599abc1b0"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "307b57b2fc6688a8a98f15b6c2ff7b9599abc1b0",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8903698906518489824"
},
{
"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"
}
}
[12:27:36.193] Scheduled build:
{
"id": "8738268844627668465",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"number": 11040,
"createdBy": "project:infra",
"createTime": "2024-08-29T12:27:35.233767061Z",
"updateTime": "2024-08-29T12:27:35.233767061Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "307b57b2fc6688a8a98f15b6c2ff7b9599abc1b0",
"ref": "refs/heads/main"
}
}
}
[12:27:36.193] Task URL: https://cr-buildbucket-dev.appspot.com/build/8738268844627668465
[12:27:36.193] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:2:0) after 8m52s
[12:27:36.712] Received PubSub notification, asking Buildbucket for the build status
[12:27:36.751] Build status: SCHEDULED
[12:36:28.212] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:2:0)
[12:36:28.243] Build status: SCHEDULED
[12:36:28.243] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:4:0) after 7m44s
[12:44:12.259] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:4:0)
[12:44:12.293] Build status: SCHEDULED
[12:44:12.293] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:5:0) after 9m49s
[12:54:01.323] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:5:0)
[12:54:01.366] Build status: SCHEDULED
[12:54:01.366] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:6:0) after 8m43s
[13:02:44.382] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:6:0)
[13:02:44.422] Build status: SCHEDULED
[13:02:44.422] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:7:0) after 6m0s
[13:08:44.439] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:7:0)
[13:08:44.485] Build status: SCHEDULED
[13:08:44.485] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:8:0) after 1m41s
[13:10:25.500] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:8:0)
[13:10:25.537] Build status: SCHEDULED
[13:10:25.537] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:9:0) after 7m35s
[13:18:00.556] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:9:0)
[13:18:00.583] Build status: SCHEDULED
[13:18:00.583] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:10:0) after 4m16s
[13:22:16.599] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:10:0)
[13:22:16.646] Build status: SCHEDULED
[13:22:16.646] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:11:0) after 6m1s
[13:28:17.664] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:11:0)
[13:28:17.722] Build status: SCHEDULED
[13:28:17.723] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:12:0) after 9m8s
[13:37:26.474] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:12:0)
[13:37:26.565] Build status: SCHEDULED
[13:37:26.565] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:13:0) after 1m37s
[13:39:04.478] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:13:0)
[13:39:04.556] Build status: SCHEDULED
[13:39:04.556] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:14:0) after 4m45s
[13:43:49.575] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:14:0)
[13:43:49.661] Build status: SCHEDULED
[13:43:49.661] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:15:0) after 1m58s
[13:45:47.681] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:15:0)
[13:45:47.719] Build status: SCHEDULED
[13:45:47.719] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:16:0) after 2m23s
[13:48:10.735] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:16:0)
[13:48:10.768] Build status: SCHEDULED
[13:48:10.768] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:17:0) after 1m47s
[13:49:57.788] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:17:0)
[13:49:57.865] Build status: SCHEDULED
[13:49:57.865] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:18:0) after 8m50s
[13:58:47.879] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:18:0)
[13:58:47.934] Build status: SCHEDULED
[13:58:47.934] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:19:0) after 5m28s
[14:04:15.959] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:19:0)
[14:04:16.010] Build status: SCHEDULED
[14:04:16.011] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:20:0) after 8m26s
[14:12:42.032] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:20:0)
[14:12:42.118] Build status: SCHEDULED
[14:12:42.118] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:21:0) after 1m29s
[14:14:11.139] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:21:0)
[14:14:11.187] Build status: SCHEDULED
[14:14:11.187] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:22:0) after 2m4s
[14:16:15.203] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:22:0)
[14:16:15.273] Build status: SCHEDULED
[14:16:15.273] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:23:0) after 2m43s
[14:18:58.290] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:23:0)
[14:18:58.316] Build status: SCHEDULED
[14:18:58.316] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:24:0) after 6m1s
[14:24:59.334] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:24:0)
[14:24:59.376] Build status: SCHEDULED
[14:24:59.376] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:25:0) after 5m27s
[14:30:26.395] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:25:0)
[14:30:26.424] Build status: SCHEDULED
[14:30:26.424] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:26:0) after 7m39s
[14:38:05.453] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:26:0)
[14:38:05.501] Build status: SCHEDULED
[14:38:05.501] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:27:0) after 3m29s
[14:41:34.521] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:27:0)
[14:41:35.919] Build status: SCHEDULED
[14:41:35.919] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:28:0) after 4m16s
[14:45:51.936] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:28:0)
[14:45:51.984] Build status: SCHEDULED
[14:45:51.984] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:29:0) after 4m26s
[14:50:18.000] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:29:0)
[14:50:18.037] Build status: SCHEDULED
[14:50:18.037] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:30:0) after 1m9s
[14:51:27.052] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:30:0)
[14:51:27.085] Build status: SCHEDULED
[14:51:27.085] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:31:0) after 2m1s
[14:53:28.511] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:31:0)
[14:53:28.571] Build status: SCHEDULED
[14:53:28.571] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:32:0) after 3m35s
[14:57:03.589] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:32:0)
[14:57:03.611] Build status: SCHEDULED
[14:57:03.611] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:33:0) after 5m1s
[15:02:04.632] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:33:0)
[15:02:04.667] Build status: SCHEDULED
[15:02:04.667] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:34:0) after 1m40s
[15:03:44.695] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:34:0)
[15:03:44.774] Build status: SCHEDULED
[15:03:44.774] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:35:0) after 1m24s
[15:05:08.804] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:35:0)
[15:05:08.851] Build status: SCHEDULED
[15:05:08.851] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:36:0) after 3m20s
[15:08:28.876] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:36:0)
[15:08:28.920] Build status: SCHEDULED
[15:08:28.920] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:37:0) after 2m34s
[15:11:02.949] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:37:0)
[15:11:02.987] Build status: SCHEDULED
[15:11:02.987] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:38:0) after 2m44s
[15:13:47.842] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:38:0)
[15:13:47.919] Build status: SCHEDULED
[15:13:47.919] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:39:0) after 7m21s
[15:21:08.955] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:39:0)
[15:21:08.986] Build status: SCHEDULED
[15:21:08.986] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:40:0) after 1m23s
[15:22:32.014] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:40:0)
[15:22:32.061] Build status: SCHEDULED
[15:22:32.061] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:41:0) after 7m18s
[15:29:50.078] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:41:0)
[15:29:50.108] Build status: SCHEDULED
[15:29:50.108] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:42:0) after 4m38s
[15:31:06.644] Received PubSub notification, asking Buildbucket for the build status
[15:31:06.681] Build status: STARTED
[15:34:28.125] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:42:0)
[15:34:28.167] Build status: STARTED
[15:34:28.167] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:44:0) after 5m16s
[15:39:44.184] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:44:0)
[15:39:44.213] Build status: STARTED
[15:39:44.213] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:45:0) after 4m22s
[15:44:06.231] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:45:0)
[15:44:06.321] Build status: STARTED
[15:44:06.321] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8903698906518489824:46:0) after 4m56s
[15:44:28.841] Received PubSub notification, asking Buildbucket for the build status
[15:44:28.881] Build:
{
"id": "8738268844627668465",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"number": 11040,
"createdBy": "project:infra",
"createTime": "2024-08-29T12:27:35.233767061Z",
"startTime": "2024-08-29T15:31:06.353409410Z",
"endTime": "2024-08-29T15:44:28.167612359Z",
"updateTime": "2024-08-29T15:44:28.167612359Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "307b57b2fc6688a8a98f15b6c2ff7b9599abc1b0",
"ref": "refs/heads/main"
}
}
}
[15:44:28.881] Invocation finished in 3h16m54.835886487s with status FAILED