[20:12:36.138] New invocation is queued and will start shortly
[20:12:37.220] Starting the invocation (attempt 1)
[20:12:37.270] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/3d51c14d421ff3c93aa939b177d7bfabe11a8d78"
[20:12:37.270] Popped gitiles commit info from properties and tags
[20:12:37.270] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[20:12:37.271] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[20:12:37.271] Buildbucket request:
{
"requestId": "9002692133348316928",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "1261995f6a1419b02e71e543cfdd81e77e319ee8"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1261995f6a1419b02e71e543cfdd81e77e319ee8",
"title": "1261995f6a1419b02e71e543cfdd81e77e319ee8",
"url": "https://chromium.googlesource.com/infra/infra/+/1261995f6a1419b02e71e543cfdd81e77e319ee8"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "981107a5530793ab5f1ffa832a114db87e383fba"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@981107a5530793ab5f1ffa832a114db87e383fba",
"title": "981107a5530793ab5f1ffa832a114db87e383fba",
"url": "https://chromium.googlesource.com/infra/infra/+/981107a5530793ab5f1ffa832a114db87e383fba"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "79a966446e3de0ab6d7291907d9674b4abe18c1b"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@79a966446e3de0ab6d7291907d9674b4abe18c1b",
"title": "79a966446e3de0ab6d7291907d9674b4abe18c1b",
"url": "https://chromium.googlesource.com/infra/infra/+/79a966446e3de0ab6d7291907d9674b4abe18c1b"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "156d47982d1671dfd65b31d9b7f6154679e0a94a"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@156d47982d1671dfd65b31d9b7f6154679e0a94a",
"title": "156d47982d1671dfd65b31d9b7f6154679e0a94a",
"url": "https://chromium.googlesource.com/infra/infra/+/156d47982d1671dfd65b31d9b7f6154679e0a94a"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "041c4ee1badd3a7c7342cf969617a3f5f24d70a0"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@041c4ee1badd3a7c7342cf969617a3f5f24d70a0",
"title": "041c4ee1badd3a7c7342cf969617a3f5f24d70a0",
"url": "https://chromium.googlesource.com/infra/infra/+/041c4ee1badd3a7c7342cf969617a3f5f24d70a0"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "d20289347ebe6261d084a26dca7dc59756f3ee45"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d20289347ebe6261d084a26dca7dc59756f3ee45",
"title": "d20289347ebe6261d084a26dca7dc59756f3ee45",
"url": "https://chromium.googlesource.com/infra/infra/+/d20289347ebe6261d084a26dca7dc59756f3ee45"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "09fa1ad466fc07f6519508081eddaccf784237b7"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@09fa1ad466fc07f6519508081eddaccf784237b7",
"title": "09fa1ad466fc07f6519508081eddaccf784237b7",
"url": "https://chromium.googlesource.com/infra/infra/+/09fa1ad466fc07f6519508081eddaccf784237b7"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "7a16a8ba6e94b0552f79c04e9fccd583b820c176"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@7a16a8ba6e94b0552f79c04e9fccd583b820c176",
"title": "7a16a8ba6e94b0552f79c04e9fccd583b820c176",
"url": "https://chromium.googlesource.com/infra/infra/+/7a16a8ba6e94b0552f79c04e9fccd583b820c176"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "ddb6071fd9103c2e4e54953fb324baa9fb63f9cb"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ddb6071fd9103c2e4e54953fb324baa9fb63f9cb",
"title": "ddb6071fd9103c2e4e54953fb324baa9fb63f9cb",
"url": "https://chromium.googlesource.com/infra/infra/+/ddb6071fd9103c2e4e54953fb324baa9fb63f9cb"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "1c67390c38a3a91a314877c5120e748741f3d81b"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1c67390c38a3a91a314877c5120e748741f3d81b",
"title": "1c67390c38a3a91a314877c5120e748741f3d81b",
"url": "https://chromium.googlesource.com/infra/infra/+/1c67390c38a3a91a314877c5120e748741f3d81b"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "f55cda74ea2941f785a016caddb9ea03f20a5e39"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f55cda74ea2941f785a016caddb9ea03f20a5e39",
"title": "f55cda74ea2941f785a016caddb9ea03f20a5e39",
"url": "https://chromium.googlesource.com/infra/infra/+/f55cda74ea2941f785a016caddb9ea03f20a5e39"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "8129389b15253f164a2417530d4fd38a039419e4"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8129389b15253f164a2417530d4fd38a039419e4",
"title": "8129389b15253f164a2417530d4fd38a039419e4",
"url": "https://chromium.googlesource.com/infra/infra/+/8129389b15253f164a2417530d4fd38a039419e4"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "488f7455e3d3b6f53e76cae1c8ace1458d6e79b0"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@488f7455e3d3b6f53e76cae1c8ace1458d6e79b0",
"title": "488f7455e3d3b6f53e76cae1c8ace1458d6e79b0",
"url": "https://chromium.googlesource.com/infra/infra/+/488f7455e3d3b6f53e76cae1c8ace1458d6e79b0"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "3d51c14d421ff3c93aa939b177d7bfabe11a8d78"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@3d51c14d421ff3c93aa939b177d7bfabe11a8d78",
"title": "3d51c14d421ff3c93aa939b177d7bfabe11a8d78",
"url": "https://chromium.googlesource.com/infra/infra/+/3d51c14d421ff3c93aa939b177d7bfabe11a8d78"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "3d51c14d421ff3c93aa939b177d7bfabe11a8d78",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9002692133348316928"
},
{
"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:12:38.317] Scheduled build:
{
"id": "8837262071433968545",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 20539,
"createdBy": "project:infra",
"createTime": "2021-09-01T20:12:37.330480944Z",
"updateTime": "2021-09-01T20:12:38.031394764Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "3d51c14d421ff3c93aa939b177d7bfabe11a8d78",
"ref": "refs/heads/master"
}
}
}
[20:12:38.317] Task URL: https://cr-buildbucket-dev.appspot.com/build/8837262071433968545
[20:12:38.317] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:2:0) after 4m52s
[20:17:30.340] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:2:0)
[20:17:30.377] Build status: SCHEDULED
[20:17:30.377] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:3:0) after 3m2s
[20:20:32.399] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:3:0)
[20:20:32.427] Build status: SCHEDULED
[20:20:32.427] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:4:0) after 6m37s
[20:27:09.491] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:4:0)
[20:27:09.530] Build status: SCHEDULED
[20:27:09.530] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:5:0) after 1m9s
[20:28:19.312] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:5:0)
[20:28:19.348] Build status: SCHEDULED
[20:28:19.349] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:6:0) after 1m41s
[20:30:00.374] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:6:0)
[20:30:00.449] Build status: SCHEDULED
[20:30:00.449] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:7:0) after 2m42s
[20:32:42.469] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:7:0)
[20:32:42.515] Build status: SCHEDULED
[20:32:42.515] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:8:0) after 4m33s
[20:37:15.543] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:8:0)
[20:37:15.582] Build status: SCHEDULED
[20:37:15.582] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:9:0) after 1m40s
[20:38:55.622] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:9:0)
[20:38:55.705] Build status: SCHEDULED
[20:38:55.705] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:10:0) after 4m56s
[20:43:51.739] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:10:0)
[20:43:51.772] Build status: SCHEDULED
[20:43:51.772] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:11:0) after 2m11s
[20:46:02.801] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:11:0)
[20:46:02.840] Build status: SCHEDULED
[20:46:02.840] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:12:0) after 9m57s
[20:55:59.859] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:12:0)
[20:55:59.925] Build status: SCHEDULED
[20:55:59.925] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:13:0) after 6m26s
[21:02:25.945] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:13:0)
[21:02:25.974] Build status: SCHEDULED
[21:02:25.974] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:14:0) after 2m39s
[21:05:04.997] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:14:0)
[21:05:05.038] Build status: SCHEDULED
[21:05:05.038] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:15:0) after 5m3s
[21:10:08.818] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:15:0)
[21:10:08.845] Build status: SCHEDULED
[21:10:08.845] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:16:0) after 2m27s
[21:12:35.867] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:16:0)
[21:12:35.927] Build status: SCHEDULED
[21:12:35.927] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:17:0) after 9m38s
[21:22:13.947] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:17:0)
[21:22:13.980] Build status: SCHEDULED
[21:22:13.980] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:18:0) after 8m58s
[21:31:12.029] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:18:0)
[21:31:12.078] Build status: SCHEDULED
[21:31:12.078] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:19:0) after 8m42s
[21:39:54.122] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:19:0)
[21:39:54.151] Build status: SCHEDULED
[21:39:54.151] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:20:0) after 6m44s
[21:46:38.179] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:20:0)
[21:46:38.210] Build status: SCHEDULED
[21:46:38.210] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:21:0) after 5m32s
[21:52:10.228] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:21:0)
[21:52:10.253] Build status: SCHEDULED
[21:52:10.253] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:22:0) after 3m53s
[21:56:03.282] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:22:0)
[21:56:03.331] Build status: SCHEDULED
[21:56:03.331] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:23:0) after 3m35s
[21:59:38.351] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:23:0)
[21:59:38.391] Build status: SCHEDULED
[21:59:38.391] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:24:0) after 9m15s
[22:04:57.799] Received PubSub notification, asking Buildbucket for the build status
[22:04:57.833] Build status: STARTED
[22:08:53.493] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:24:0)
[22:08:53.556] Build status: STARTED
[22:08:53.556] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:26:0) after 3m58s
[22:12:51.582] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:26:0)
[22:12:51.611] Build status: STARTED
[22:12:51.611] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:27:0) after 7m25s
[22:20:16.635] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:27:0)
[22:20:16.674] Build status: STARTED
[22:20:16.674] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:28:0) after 1m6s
[22:21:22.693] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:28:0)
[22:21:23.186] Build status: STARTED
[22:21:23.186] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9002692133348316928:29:0) after 8m0s
[22:23:33.251] Received PubSub notification, asking Buildbucket for the build status
[22:23:33.326] Build:
{
"id": "8837262071433968545",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 20539,
"createdBy": "project:infra",
"createTime": "2021-09-01T20:12:37.330480944Z",
"startTime": "2021-09-01T22:04:49.462089Z",
"endTime": "2021-09-01T22:23:20.929417881Z",
"updateTime": "2021-09-01T22:23:20.929771573Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "3d51c14d421ff3c93aa939b177d7bfabe11a8d78",
"ref": "refs/heads/master"
}
}
}
[22:23:33.326] Invocation finished in 2h10m57.204607416s with status SUCCEEDED