[21:20:06.068] New invocation is queued and will start shortly
[21:20:07.151] Starting the invocation (attempt 1)
[21:20:07.197] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/51d10c7d84a1c0b53fd5300f55bf56b61b17ab65"
[21:20:07.197] Popped gitiles commit info from properties and tags
[21:20:07.197] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[21:20:07.197] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[21:20:07.197] Buildbucket request:
{
"requestId": "8981125808684787248",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8981125808684787248",
"job": "infra/infra-continuous-win11-64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "66ce83566f82d8955ece006138e938fb928324b0"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@66ce83566f82d8955ece006138e938fb928324b0",
"title": "66ce83566f82d8955ece006138e938fb928324b0",
"url": "https://chromium.googlesource.com/infra/infra/+/66ce83566f82d8955ece006138e938fb928324b0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "00453b3c4b5ace5ce90c8d676cf3ea7379d40f3c"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@00453b3c4b5ace5ce90c8d676cf3ea7379d40f3c",
"title": "00453b3c4b5ace5ce90c8d676cf3ea7379d40f3c",
"url": "https://chromium.googlesource.com/infra/infra/+/00453b3c4b5ace5ce90c8d676cf3ea7379d40f3c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "6be6e141d1fb1403fd8c3ff8471b4fb69867ba8f"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@6be6e141d1fb1403fd8c3ff8471b4fb69867ba8f",
"title": "6be6e141d1fb1403fd8c3ff8471b4fb69867ba8f",
"url": "https://chromium.googlesource.com/infra/infra/+/6be6e141d1fb1403fd8c3ff8471b4fb69867ba8f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "aac35dbf162bd6bdbf780a602e9df8432f0a3330"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@aac35dbf162bd6bdbf780a602e9df8432f0a3330",
"title": "aac35dbf162bd6bdbf780a602e9df8432f0a3330",
"url": "https://chromium.googlesource.com/infra/infra/+/aac35dbf162bd6bdbf780a602e9df8432f0a3330"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "568e1429189290bc1e4fb48e2500243d9c8ca84c"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@568e1429189290bc1e4fb48e2500243d9c8ca84c",
"title": "568e1429189290bc1e4fb48e2500243d9c8ca84c",
"url": "https://chromium.googlesource.com/infra/infra/+/568e1429189290bc1e4fb48e2500243d9c8ca84c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "51d10c7d84a1c0b53fd5300f55bf56b61b17ab65"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@51d10c7d84a1c0b53fd5300f55bf56b61b17ab65",
"title": "51d10c7d84a1c0b53fd5300f55bf56b61b17ab65",
"url": "https://chromium.googlesource.com/infra/infra/+/51d10c7d84a1c0b53fd5300f55bf56b61b17ab65"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "51d10c7d84a1c0b53fd5300f55bf56b61b17ab65",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8981125808684787248"
},
{
"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:20:08.024] Scheduled build:
{
"id": "8815695746724853361",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"number": 988,
"createdBy": "project:infra",
"createTime": "2022-04-27T21:20:07.304259041Z",
"updateTime": "2022-04-27T21:20:07.304259041Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "51d10c7d84a1c0b53fd5300f55bf56b61b17ab65",
"ref": "refs/heads/main"
}
}
}
[21:20:08.024] Task URL: https://cr-buildbucket-dev.appspot.com/build/8815695746724853361
[21:20:08.024] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:2:0) after 8m28s
[21:28:36.043] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:2:0)
[21:28:36.085] Build status: SCHEDULED
[21:28:36.085] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:3:0) after 9m32s
[21:38:08.103] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:3:0)
[21:38:08.139] Build status: SCHEDULED
[21:38:08.139] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:4:0) after 1m16s
[21:39:24.166] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:4:0)
[21:39:24.187] Build status: SCHEDULED
[21:39:24.187] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:5:0) after 3m4s
[21:42:28.218] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:5:0)
[21:42:28.250] Build status: SCHEDULED
[21:42:28.250] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:6:0) after 6m53s
[21:49:21.268] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:6:0)
[21:49:21.294] Build status: SCHEDULED
[21:49:21.294] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:7:0) after 2m49s
[21:52:10.311] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:7:0)
[21:52:10.355] Build status: SCHEDULED
[21:52:10.355] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:8:0) after 1m4s
[21:53:14.390] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:8:0)
[21:53:14.456] Build status: SCHEDULED
[21:53:14.456] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:9:0) after 9m24s
[22:02:38.474] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:9:0)
[22:02:38.517] Build status: SCHEDULED
[22:02:38.517] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:10:0) after 8m54s
[22:11:32.540] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:10:0)
[22:11:32.589] Build status: SCHEDULED
[22:11:32.589] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:11:0) after 2m38s
[22:14:10.607] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:11:0)
[22:14:10.646] Build status: SCHEDULED
[22:14:10.646] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:12:0) after 4m47s
[22:18:57.665] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:12:0)
[22:18:57.718] Build status: SCHEDULED
[22:18:57.718] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:13:0) after 6m23s
[22:25:20.745] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:13:0)
[22:25:20.801] Build status: SCHEDULED
[22:25:20.801] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:14:0) after 7m37s
[22:32:57.821] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:14:0)
[22:32:57.876] Build status: SCHEDULED
[22:32:57.876] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:15:0) after 4m24s
[22:37:21.898] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:15:0)
[22:37:21.954] Build status: SCHEDULED
[22:37:21.954] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:16:0) after 7m36s
[22:44:57.991] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:16:0)
[22:44:58.040] Build status: SCHEDULED
[22:44:58.040] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:17:0) after 1m26s
[22:46:24.059] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:17:0)
[22:46:24.105] Build status: SCHEDULED
[22:46:24.105] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:18:0) after 5m53s
[22:52:17.125] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:18:0)
[22:52:17.168] Build status: SCHEDULED
[22:52:17.168] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:19:0) after 9m20s
[22:56:46.254] Received PubSub notification, asking Buildbucket for the build status
[22:56:46.305] Build status: STARTED
[23:01:37.188] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:19:0)
[23:01:37.282] Build status: STARTED
[23:01:37.282] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:21:0) after 7m55s
[23:09:32.304] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:21:0)
[23:09:32.377] Build status: STARTED
[23:09:32.377] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8981125808684787248:22:0) after 8m42s
[23:16:07.441] Received PubSub notification, asking Buildbucket for the build status
[23:16:07.476] Build:
{
"id": "8815695746724853361",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"number": 988,
"createdBy": "project:infra",
"createTime": "2022-04-27T21:20:07.304259041Z",
"startTime": "2022-04-27T22:56:44.565904Z",
"endTime": "2022-04-27T23:16:07.125360343Z",
"updateTime": "2022-04-27T23:16:07.125360343Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "51d10c7d84a1c0b53fd5300f55bf56b61b17ab65",
"ref": "refs/heads/main"
}
}
}
[23:16:07.476] Invocation finished in 1h56m1.424374458s with status SUCCEEDED