[01:21:10.067] New invocation is queued and will start shortly
[01:21:11.133] Starting the invocation (attempt 1)
[01:21:11.164] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/3812137a21da00d338dda6aafaf9954a433c163c"
[01:21:11.164] Popped gitiles commit info from properties and tags
[01:21:11.164] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[01:21:11.165] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[01:21:11.166] Buildbucket request:
{
"requestId": "8935993352804842896",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8935993352804842896",
"job": "infra/infra-continuous-win10-64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "02c6385569fed74718eb7ba04e54db59d206fb3d"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@02c6385569fed74718eb7ba04e54db59d206fb3d",
"title": "02c6385569fed74718eb7ba04e54db59d206fb3d",
"url": "https://chromium.googlesource.com/infra/infra/+/02c6385569fed74718eb7ba04e54db59d206fb3d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "c8e97644226c5e5baa62515a504fc642a54a4fc2"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@c8e97644226c5e5baa62515a504fc642a54a4fc2",
"title": "c8e97644226c5e5baa62515a504fc642a54a4fc2",
"url": "https://chromium.googlesource.com/infra/infra/+/c8e97644226c5e5baa62515a504fc642a54a4fc2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "a66ed6eadc78a7f53df3fae1229c4e8dfe9cd11f"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@a66ed6eadc78a7f53df3fae1229c4e8dfe9cd11f",
"title": "a66ed6eadc78a7f53df3fae1229c4e8dfe9cd11f",
"url": "https://chromium.googlesource.com/infra/infra/+/a66ed6eadc78a7f53df3fae1229c4e8dfe9cd11f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "5846e109fb06fe1bc43e2f2af6ae000d6312a122"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@5846e109fb06fe1bc43e2f2af6ae000d6312a122",
"title": "5846e109fb06fe1bc43e2f2af6ae000d6312a122",
"url": "https://chromium.googlesource.com/infra/infra/+/5846e109fb06fe1bc43e2f2af6ae000d6312a122"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "3cf3c2a30d1baf6d12958f25e7af58388027d1e8"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@3cf3c2a30d1baf6d12958f25e7af58388027d1e8",
"title": "3cf3c2a30d1baf6d12958f25e7af58388027d1e8",
"url": "https://chromium.googlesource.com/infra/infra/+/3cf3c2a30d1baf6d12958f25e7af58388027d1e8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "bff45786be4c1a7b7d3cd420e0003bf7586afce5"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@bff45786be4c1a7b7d3cd420e0003bf7586afce5",
"title": "bff45786be4c1a7b7d3cd420e0003bf7586afce5",
"url": "https://chromium.googlesource.com/infra/infra/+/bff45786be4c1a7b7d3cd420e0003bf7586afce5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "054d5678772018b5496d3af7d30b0a75e2a09a44"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@054d5678772018b5496d3af7d30b0a75e2a09a44",
"title": "054d5678772018b5496d3af7d30b0a75e2a09a44",
"url": "https://chromium.googlesource.com/infra/infra/+/054d5678772018b5496d3af7d30b0a75e2a09a44"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "cd98eca3de2a09db9ecfbc157f22319d7166d92b"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@cd98eca3de2a09db9ecfbc157f22319d7166d92b",
"title": "cd98eca3de2a09db9ecfbc157f22319d7166d92b",
"url": "https://chromium.googlesource.com/infra/infra/+/cd98eca3de2a09db9ecfbc157f22319d7166d92b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "19a4a6c19922f7e704c2569314e01e13c40976e6"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@19a4a6c19922f7e704c2569314e01e13c40976e6",
"title": "19a4a6c19922f7e704c2569314e01e13c40976e6",
"url": "https://chromium.googlesource.com/infra/infra/+/19a4a6c19922f7e704c2569314e01e13c40976e6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "ff83a4ee2604c19f47c870409415c0f941cbb528"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@ff83a4ee2604c19f47c870409415c0f941cbb528",
"title": "ff83a4ee2604c19f47c870409415c0f941cbb528",
"url": "https://chromium.googlesource.com/infra/infra/+/ff83a4ee2604c19f47c870409415c0f941cbb528"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "11a59ed4358d2e6b212b83845f6885a5eae8b125"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@11a59ed4358d2e6b212b83845f6885a5eae8b125",
"title": "11a59ed4358d2e6b212b83845f6885a5eae8b125",
"url": "https://chromium.googlesource.com/infra/infra/+/11a59ed4358d2e6b212b83845f6885a5eae8b125"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "3812137a21da00d338dda6aafaf9954a433c163c"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@3812137a21da00d338dda6aafaf9954a433c163c",
"title": "3812137a21da00d338dda6aafaf9954a433c163c",
"url": "https://chromium.googlesource.com/infra/infra/+/3812137a21da00d338dda6aafaf9954a433c163c"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "3812137a21da00d338dda6aafaf9954a433c163c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8935993352804842896"
},
{
"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"
}
}
[01:21:12.024] Scheduled build:
{
"id": "8770563290939029489",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 25734,
"createdBy": "project:infra",
"createTime": "2023-09-08T01:21:11.223524804Z",
"updateTime": "2023-09-08T01:21:11.223524804Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "3812137a21da00d338dda6aafaf9954a433c163c",
"ref": "refs/heads/main"
}
}
}
[01:21:12.024] Task URL: https://cr-buildbucket-dev.appspot.com/build/8770563290939029489
[01:21:12.024] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:2:0) after 1m16s
[01:21:12.981] Received PubSub notification, asking Buildbucket for the build status
[01:21:13.004] Build status: SCHEDULED
[01:22:28.043] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:2:0)
[01:22:28.073] Build status: SCHEDULED
[01:22:28.074] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:4:0) after 6m3s
[01:28:31.102] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:4:0)
[01:28:31.130] Build status: SCHEDULED
[01:28:31.130] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:5:0) after 4m10s
[01:32:41.150] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:5:0)
[01:32:41.181] Build status: SCHEDULED
[01:32:41.181] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:6:0) after 7m19s
[01:40:00.198] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:6:0)
[01:40:00.228] Build status: SCHEDULED
[01:40:00.228] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:7:0) after 9m8s
[01:49:08.243] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:7:0)
[01:49:08.290] Build status: SCHEDULED
[01:49:08.290] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:8:0) after 7m35s
[01:56:43.306] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:8:0)
[01:56:43.335] Build status: SCHEDULED
[01:56:43.335] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:9:0) after 8m3s
[02:04:46.351] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:9:0)
[02:04:46.385] Build status: SCHEDULED
[02:04:46.385] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:10:0) after 9m50s
[02:14:36.471] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:10:0)
[02:14:36.503] Build status: SCHEDULED
[02:14:36.503] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:11:0) after 4m22s
[02:18:58.520] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:11:0)
[02:18:58.538] Build status: SCHEDULED
[02:18:58.538] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:12:0) after 3m50s
[02:22:48.556] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:12:0)
[02:22:48.582] Build status: SCHEDULED
[02:22:48.582] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:13:0) after 4m10s
[02:26:59.341] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:13:0)
[02:26:59.380] Build status: SCHEDULED
[02:26:59.380] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:14:0) after 1m33s
[02:27:54.860] Received PubSub notification, asking Buildbucket for the build status
[02:27:54.888] Build status: STARTED
[02:28:32.398] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:14:0)
[02:28:32.442] Build status: STARTED
[02:28:32.442] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:16:0) after 5m2s
[02:33:34.459] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:16:0)
[02:33:34.491] Build status: STARTED
[02:33:34.491] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:17:0) after 3m8s
[02:36:42.527] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:17:0)
[02:36:42.563] Build status: STARTED
[02:36:42.563] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8935993352804842896:18:0) after 4m48s
[02:41:14.853] Received PubSub notification, asking Buildbucket for the build status
[02:41:14.888] Build:
{
"id": "8770563290939029489",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 25734,
"createdBy": "project:infra",
"createTime": "2023-09-08T01:21:11.223524804Z",
"startTime": "2023-09-08T02:27:54.109966Z",
"endTime": "2023-09-08T02:41:11.969616390Z",
"updateTime": "2023-09-08T02:41:11.969616390Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "3812137a21da00d338dda6aafaf9954a433c163c",
"ref": "refs/heads/main"
}
}
}
[02:41:14.888] Invocation finished in 1h20m4.826657568s with status SUCCEEDED