[20:32:26.137] New invocation is queued and will start shortly
[20:32:27.253] Starting the invocation (attempt 1)
[20:32:27.302] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/9f32e02d53adcec4881541e57dcfe38a6b829ced"
[20:32:27.302] Popped gitiles commit info from properties and tags
[20:32:27.302] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[20:32:27.304] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[20:32:27.304] Buildbucket request:
{
"requestId": "8965364935400117872",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8965364935400117872",
"job": "infra/infra-continuous-win10-64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "9df013ec3a8a472065eca8b04b0c881f521e0f63"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@9df013ec3a8a472065eca8b04b0c881f521e0f63",
"title": "9df013ec3a8a472065eca8b04b0c881f521e0f63",
"url": "https://chromium.googlesource.com/infra/infra/+/9df013ec3a8a472065eca8b04b0c881f521e0f63"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "6e48e158043b35047264f164db2ed92f3b0cb8c9"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@6e48e158043b35047264f164db2ed92f3b0cb8c9",
"title": "6e48e158043b35047264f164db2ed92f3b0cb8c9",
"url": "https://chromium.googlesource.com/infra/infra/+/6e48e158043b35047264f164db2ed92f3b0cb8c9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "8fb0573170eb31804b64cbce4b665af3aeb5f59c"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@8fb0573170eb31804b64cbce4b665af3aeb5f59c",
"title": "8fb0573170eb31804b64cbce4b665af3aeb5f59c",
"url": "https://chromium.googlesource.com/infra/infra/+/8fb0573170eb31804b64cbce4b665af3aeb5f59c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "72d33bc136f08b5478a3a6fcae4ebe1927c38cd1"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@72d33bc136f08b5478a3a6fcae4ebe1927c38cd1",
"title": "72d33bc136f08b5478a3a6fcae4ebe1927c38cd1",
"url": "https://chromium.googlesource.com/infra/infra/+/72d33bc136f08b5478a3a6fcae4ebe1927c38cd1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "60586f6ba4d0a9a4b91275561c94ac1af8283770"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@60586f6ba4d0a9a4b91275561c94ac1af8283770",
"title": "60586f6ba4d0a9a4b91275561c94ac1af8283770",
"url": "https://chromium.googlesource.com/infra/infra/+/60586f6ba4d0a9a4b91275561c94ac1af8283770"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "e7af8330a671412a425301cca3d68dd59bfcf3be"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@e7af8330a671412a425301cca3d68dd59bfcf3be",
"title": "e7af8330a671412a425301cca3d68dd59bfcf3be",
"url": "https://chromium.googlesource.com/infra/infra/+/e7af8330a671412a425301cca3d68dd59bfcf3be"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "4cd20cc9654347ed35cfddf964675ffba20ff709"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@4cd20cc9654347ed35cfddf964675ffba20ff709",
"title": "4cd20cc9654347ed35cfddf964675ffba20ff709",
"url": "https://chromium.googlesource.com/infra/infra/+/4cd20cc9654347ed35cfddf964675ffba20ff709"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "78d51601a9b741cd44117e7c3e706166ebb6c580"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@78d51601a9b741cd44117e7c3e706166ebb6c580",
"title": "78d51601a9b741cd44117e7c3e706166ebb6c580",
"url": "https://chromium.googlesource.com/infra/infra/+/78d51601a9b741cd44117e7c3e706166ebb6c580"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "71006e7622a8832909991d5619807849a8b1cb5c"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@71006e7622a8832909991d5619807849a8b1cb5c",
"title": "71006e7622a8832909991d5619807849a8b1cb5c",
"url": "https://chromium.googlesource.com/infra/infra/+/71006e7622a8832909991d5619807849a8b1cb5c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "9f32e02d53adcec4881541e57dcfe38a6b829ced"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@9f32e02d53adcec4881541e57dcfe38a6b829ced",
"title": "9f32e02d53adcec4881541e57dcfe38a6b829ced",
"url": "https://chromium.googlesource.com/infra/infra/+/9f32e02d53adcec4881541e57dcfe38a6b829ced"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "9f32e02d53adcec4881541e57dcfe38a6b829ced",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8965364935400117872"
},
{
"key": "scheduler_job_id",
"value": "infra/infra-continuous-win10-64"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[20:32:27.929] Scheduled build:
{
"id": "8799934873355556065",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 23682,
"createdBy": "project:infra",
"createTime": "2022-10-18T20:32:27.441208315Z",
"updateTime": "2022-10-18T20:32:27.441208315Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "9f32e02d53adcec4881541e57dcfe38a6b829ced",
"ref": "refs/heads/main"
}
}
}
[20:32:27.929] Task URL: https://cr-buildbucket-dev.appspot.com/build/8799934873355556065
[20:32:27.929] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:2:0) after 1m59s
[20:34:26.957] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:2:0)
[20:34:26.982] Build status: SCHEDULED
[20:34:26.982] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:3:0) after 4m15s
[20:38:42.007] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:3:0)
[20:38:42.037] Build status: SCHEDULED
[20:38:42.037] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:4:0) after 5m50s
[20:44:32.063] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:4:0)
[20:44:32.091] Build status: SCHEDULED
[20:44:32.091] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:5:0) after 3m41s
[20:48:13.119] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:5:0)
[20:48:13.154] Build status: SCHEDULED
[20:48:13.154] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:6:0) after 8m50s
[20:57:03.185] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:6:0)
[20:57:03.685] Build status: SCHEDULED
[20:57:03.686] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:7:0) after 5m50s
[21:02:53.716] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:7:0)
[21:02:53.784] Build status: SCHEDULED
[21:02:53.784] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:8:0) after 9m0s
[21:11:53.821] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:8:0)
[21:11:53.848] Build status: SCHEDULED
[21:11:53.848] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:9:0) after 8m31s
[21:20:24.877] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:9:0)
[21:20:24.909] Build status: SCHEDULED
[21:20:24.909] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:10:0) after 3m33s
[21:23:57.965] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:10:0)
[21:23:57.998] Build status: SCHEDULED
[21:23:57.998] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:11:0) after 2m26s
[21:26:24.027] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:11:0)
[21:26:24.062] Build status: SCHEDULED
[21:26:24.062] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:12:0) after 4m18s
[21:30:42.102] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:12:0)
[21:30:42.142] Build status: SCHEDULED
[21:30:42.142] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:13:0) after 2m58s
[21:33:40.168] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:13:0)
[21:33:40.204] Build status: SCHEDULED
[21:33:40.205] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:14:0) after 8m49s
[21:42:29.234] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:14:0)
[21:42:29.260] Build status: SCHEDULED
[21:42:29.260] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:15:0) after 9m20s
[21:51:49.370] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:15:0)
[21:51:49.399] Build status: SCHEDULED
[21:51:49.399] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:16:0) after 1m43s
[21:53:32.407] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:16:0)
[21:53:32.434] Build status: SCHEDULED
[21:53:32.434] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:17:0) after 9m25s
[22:02:57.465] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:17:0)
[22:02:57.499] Build status: SCHEDULED
[22:02:57.499] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:18:0) after 6m45s
[22:09:43.089] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:18:0)
[22:09:43.118] Build status: SCHEDULED
[22:09:43.118] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:19:0) after 6m30s
[22:16:13.148] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:19:0)
[22:16:13.185] Build status: SCHEDULED
[22:16:13.186] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:20:0) after 9m40s
[22:25:53.220] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:20:0)
[22:25:53.283] Build status: SCHEDULED
[22:25:53.283] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:21:0) after 4m39s
[22:30:33.977] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:21:0)
[22:30:34.106] Build status: SCHEDULED
[22:30:34.106] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:22:0) after 8m41s
[22:35:40.567] Received PubSub notification, asking Buildbucket for the build status
[22:35:40.597] Build status: STARTED
[22:39:15.135] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:22:0)
[22:39:15.168] Build status: STARTED
[22:39:15.168] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:24:0) after 8m29s
[22:47:44.226] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:24:0)
[22:47:44.257] Build status: STARTED
[22:47:44.257] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:25:0) after 2m16s
[22:50:00.284] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:25:0)
[22:50:00.312] Build status: STARTED
[22:50:00.312] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:26:0) after 7m54s
[22:57:55.217] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:26:0)
[22:57:55.245] Build status: STARTED
[22:57:55.245] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8965364935400117872:27:0) after 6m54s
[23:00:31.097] Received PubSub notification, asking Buildbucket for the build status
[23:00:31.129] Build:
{
"id": "8799934873355556065",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 23682,
"createdBy": "project:infra",
"createTime": "2022-10-18T20:32:27.441208315Z",
"startTime": "2022-10-18T22:35:39.795011Z",
"endTime": "2022-10-18T23:00:29.729761434Z",
"updateTime": "2022-10-18T23:00:29.729761434Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "9f32e02d53adcec4881541e57dcfe38a6b829ced",
"ref": "refs/heads/main"
}
}
}
[23:00:31.129] Invocation finished in 2h28m5.022023172s with status SUCCEEDED