[05:45:04.064] New invocation is queued and will start shortly
[05:45:05.144] Starting the invocation (attempt 1)
[05:45:05.179] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/f19bc9856196d59160b5e3ccb75626b8ffb24e55"
[05:45:05.179] Popped gitiles commit info from properties and tags
[05:45:05.179] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:45:05.179] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[05:45:05.180] Buildbucket request:
{
"requestId": "8935614361790993504",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8935614361790993504",
"job": "infra/infra-continuous-win11-64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "7350b127170c8ccd3d0d95eb36ac35074f2458e4"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@7350b127170c8ccd3d0d95eb36ac35074f2458e4",
"title": "7350b127170c8ccd3d0d95eb36ac35074f2458e4",
"url": "https://chromium.googlesource.com/infra/infra/+/7350b127170c8ccd3d0d95eb36ac35074f2458e4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "2f134a0afd7499581acdfa34c1e5e6263b80becf"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@2f134a0afd7499581acdfa34c1e5e6263b80becf",
"title": "2f134a0afd7499581acdfa34c1e5e6263b80becf",
"url": "https://chromium.googlesource.com/infra/infra/+/2f134a0afd7499581acdfa34c1e5e6263b80becf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "678b69e9e032f88b7d96a21d9a0928acfe33786b"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@678b69e9e032f88b7d96a21d9a0928acfe33786b",
"title": "678b69e9e032f88b7d96a21d9a0928acfe33786b",
"url": "https://chromium.googlesource.com/infra/infra/+/678b69e9e032f88b7d96a21d9a0928acfe33786b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "6aad41fa3637ded57daf49d1e51d7820fb9dcf69"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@6aad41fa3637ded57daf49d1e51d7820fb9dcf69",
"title": "6aad41fa3637ded57daf49d1e51d7820fb9dcf69",
"url": "https://chromium.googlesource.com/infra/infra/+/6aad41fa3637ded57daf49d1e51d7820fb9dcf69"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "f19bc9856196d59160b5e3ccb75626b8ffb24e55"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@f19bc9856196d59160b5e3ccb75626b8ffb24e55",
"title": "f19bc9856196d59160b5e3ccb75626b8ffb24e55",
"url": "https://chromium.googlesource.com/infra/infra/+/f19bc9856196d59160b5e3ccb75626b8ffb24e55"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "f19bc9856196d59160b5e3ccb75626b8ffb24e55",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8935614361790993504"
},
{
"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"
}
}
[05:45:06.192] Scheduled build:
{
"id": "8770184299881623057",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"number": 4305,
"createdBy": "project:infra",
"createTime": "2023-09-12T05:45:05.261495182Z",
"updateTime": "2023-09-12T05:45:05.261495182Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "f19bc9856196d59160b5e3ccb75626b8ffb24e55",
"ref": "refs/heads/main"
}
}
}
[05:45:06.192] Task URL: https://cr-buildbucket-dev.appspot.com/build/8770184299881623057
[05:45:06.192] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:2:0) after 2m0s
[05:45:07.115] Received PubSub notification, asking Buildbucket for the build status
[05:45:07.144] Build status: SCHEDULED
[05:47:06.213] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:2:0)
[05:47:06.235] Build status: SCHEDULED
[05:47:06.235] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:4:0) after 2m54s
[05:50:00.250] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:4:0)
[05:50:00.279] Build status: SCHEDULED
[05:50:00.279] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:5:0) after 5m52s
[05:55:52.293] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:5:0)
[05:55:52.318] Build status: SCHEDULED
[05:55:52.318] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:6:0) after 6m53s
[06:02:45.337] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:6:0)
[06:02:45.376] Build status: SCHEDULED
[06:02:45.376] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:7:0) after 2m49s
[06:05:34.392] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:7:0)
[06:05:34.422] Build status: SCHEDULED
[06:05:34.422] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:8:0) after 9m46s
[06:15:20.440] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:8:0)
[06:15:20.511] Build status: SCHEDULED
[06:15:20.511] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:9:0) after 6m10s
[06:21:30.546] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:9:0)
[06:21:30.577] Build status: SCHEDULED
[06:21:30.577] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:10:0) after 3m49s
[06:25:19.593] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:10:0)
[06:25:19.628] Build status: SCHEDULED
[06:25:19.628] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:11:0) after 1m23s
[06:26:42.647] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:11:0)
[06:26:42.675] Build status: SCHEDULED
[06:26:42.675] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:12:0) after 4m10s
[06:30:52.693] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:12:0)
[06:30:52.720] Build status: SCHEDULED
[06:30:52.720] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:13:0) after 5m35s
[06:36:27.738] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:13:0)
[06:36:27.764] Build status: SCHEDULED
[06:36:27.764] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:14:0) after 7m35s
[06:44:02.781] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:14:0)
[06:44:02.833] Build status: SCHEDULED
[06:44:02.833] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:15:0) after 5m54s
[06:49:57.722] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:15:0)
[06:49:57.739] Build status: SCHEDULED
[06:49:57.739] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:16:0) after 4m34s
[06:54:31.753] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:16:0)
[06:54:31.768] Build status: SCHEDULED
[06:54:31.768] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:17:0) after 2m23s
[06:56:54.783] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:17:0)
[06:56:54.814] Build status: SCHEDULED
[06:56:54.814] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:18:0) after 3m27s
[07:00:21.843] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:18:0)
[07:00:21.863] Build status: SCHEDULED
[07:00:21.863] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:19:0) after 5m3s
[07:05:25.128] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:19:0)
[07:05:25.163] Build status: SCHEDULED
[07:05:25.163] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:20:0) after 4m3s
[07:09:28.188] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:20:0)
[07:09:28.210] Build status: SCHEDULED
[07:09:28.210] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:21:0) after 8m11s
[07:17:39.233] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:21:0)
[07:17:39.271] Build status: SCHEDULED
[07:17:39.271] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:22:0) after 8m44s
[07:26:23.294] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:22:0)
[07:26:23.319] Build status: SCHEDULED
[07:26:23.319] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:23:0) after 7m1s
[07:26:54.612] Received PubSub notification, asking Buildbucket for the build status
[07:26:54.645] Build status: STARTED
[07:33:24.336] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:23:0)
[07:33:24.357] Build status: STARTED
[07:33:24.357] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8935614361790993504:25:0) after 7m57s
[07:40:34.988] Received PubSub notification, asking Buildbucket for the build status
[07:40:35.024] Build:
{
"id": "8770184299881623057",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"number": 4305,
"createdBy": "project:infra",
"createTime": "2023-09-12T05:45:05.261495182Z",
"startTime": "2023-09-12T07:26:53.104843Z",
"endTime": "2023-09-12T07:40:34.769544966Z",
"updateTime": "2023-09-12T07:40:34.769544966Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "f19bc9856196d59160b5e3ccb75626b8ffb24e55",
"ref": "refs/heads/main"
}
}
}
[07:40:35.024] Invocation finished in 1h55m30.966918207s with status SUCCEEDED