[22:19:34.074] New invocation is queued and will start shortly
[22:19:35.145] Starting the invocation (attempt 1)
[22:19:35.182] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/339fc51de1f75fe88e35f02781649ca27c2b6977"
[22:19:35.182] Popped gitiles commit info from properties and tags
[22:19:35.182] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[22:19:35.182] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[22:19:35.183] Buildbucket request:
{
"requestId": "8973602519147444688",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8973602519147444688",
"job": "infra/infra-continuous-win10-64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "f25750d96d7de7cd68720171a94ab339ecd35c7b"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@f25750d96d7de7cd68720171a94ab339ecd35c7b",
"title": "f25750d96d7de7cd68720171a94ab339ecd35c7b",
"url": "https://chromium.googlesource.com/infra/infra/+/f25750d96d7de7cd68720171a94ab339ecd35c7b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "3990decd737b48faf66306ccab9c2ce3cd79644f"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@3990decd737b48faf66306ccab9c2ce3cd79644f",
"title": "3990decd737b48faf66306ccab9c2ce3cd79644f",
"url": "https://chromium.googlesource.com/infra/infra/+/3990decd737b48faf66306ccab9c2ce3cd79644f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "d78e0a663d5c21630a9f80cbb0652500c52f20b6"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@d78e0a663d5c21630a9f80cbb0652500c52f20b6",
"title": "d78e0a663d5c21630a9f80cbb0652500c52f20b6",
"url": "https://chromium.googlesource.com/infra/infra/+/d78e0a663d5c21630a9f80cbb0652500c52f20b6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "8b8a7faff77b917ef9d22d5ebe74c4edcf7fcd78"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@8b8a7faff77b917ef9d22d5ebe74c4edcf7fcd78",
"title": "8b8a7faff77b917ef9d22d5ebe74c4edcf7fcd78",
"url": "https://chromium.googlesource.com/infra/infra/+/8b8a7faff77b917ef9d22d5ebe74c4edcf7fcd78"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "b5af6389c407a7a456677a7ea6fb6366b45e6eeb"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@b5af6389c407a7a456677a7ea6fb6366b45e6eeb",
"title": "b5af6389c407a7a456677a7ea6fb6366b45e6eeb",
"url": "https://chromium.googlesource.com/infra/infra/+/b5af6389c407a7a456677a7ea6fb6366b45e6eeb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "a845c8bdbe008fc742f660359957e376b2a6e215"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@a845c8bdbe008fc742f660359957e376b2a6e215",
"title": "a845c8bdbe008fc742f660359957e376b2a6e215",
"url": "https://chromium.googlesource.com/infra/infra/+/a845c8bdbe008fc742f660359957e376b2a6e215"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "15cbc4cfd7effa879d66ef3b68fcab065bc9c635"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@15cbc4cfd7effa879d66ef3b68fcab065bc9c635",
"title": "15cbc4cfd7effa879d66ef3b68fcab065bc9c635",
"url": "https://chromium.googlesource.com/infra/infra/+/15cbc4cfd7effa879d66ef3b68fcab065bc9c635"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "6e7c345f40ef9d6261a32340a2d9274436d59fbc"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@6e7c345f40ef9d6261a32340a2d9274436d59fbc",
"title": "6e7c345f40ef9d6261a32340a2d9274436d59fbc",
"url": "https://chromium.googlesource.com/infra/infra/+/6e7c345f40ef9d6261a32340a2d9274436d59fbc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "e9621409f0375183d457fb3857ac075b58d72fcd"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@e9621409f0375183d457fb3857ac075b58d72fcd",
"title": "e9621409f0375183d457fb3857ac075b58d72fcd",
"url": "https://chromium.googlesource.com/infra/infra/+/e9621409f0375183d457fb3857ac075b58d72fcd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "4bbd75f92c5070e9695719a25ccdc001aaa1aa38"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@4bbd75f92c5070e9695719a25ccdc001aaa1aa38",
"title": "4bbd75f92c5070e9695719a25ccdc001aaa1aa38",
"url": "https://chromium.googlesource.com/infra/infra/+/4bbd75f92c5070e9695719a25ccdc001aaa1aa38"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "4fb1b1b789a0a7ca90b2c1266b452b46059917a1"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@4fb1b1b789a0a7ca90b2c1266b452b46059917a1",
"title": "4fb1b1b789a0a7ca90b2c1266b452b46059917a1",
"url": "https://chromium.googlesource.com/infra/infra/+/4fb1b1b789a0a7ca90b2c1266b452b46059917a1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "3da883b46390a90ec93fa39af0027c274835e2db"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@3da883b46390a90ec93fa39af0027c274835e2db",
"title": "3da883b46390a90ec93fa39af0027c274835e2db",
"url": "https://chromium.googlesource.com/infra/infra/+/3da883b46390a90ec93fa39af0027c274835e2db"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "d39cde99c1114f1844927f8507831d9143d4accd"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@d39cde99c1114f1844927f8507831d9143d4accd",
"title": "d39cde99c1114f1844927f8507831d9143d4accd",
"url": "https://chromium.googlesource.com/infra/infra/+/d39cde99c1114f1844927f8507831d9143d4accd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "5a15a41f23b30bf735ec38754cbcc34c52835b6a"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@5a15a41f23b30bf735ec38754cbcc34c52835b6a",
"title": "5a15a41f23b30bf735ec38754cbcc34c52835b6a",
"url": "https://chromium.googlesource.com/infra/infra/+/5a15a41f23b30bf735ec38754cbcc34c52835b6a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "827d17f20a5e6d47a2a2920239cfd2c4c46bde49"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@827d17f20a5e6d47a2a2920239cfd2c4c46bde49",
"title": "827d17f20a5e6d47a2a2920239cfd2c4c46bde49",
"url": "https://chromium.googlesource.com/infra/infra/+/827d17f20a5e6d47a2a2920239cfd2c4c46bde49"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "339fc51de1f75fe88e35f02781649ca27c2b6977"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@339fc51de1f75fe88e35f02781649ca27c2b6977",
"title": "339fc51de1f75fe88e35f02781649ca27c2b6977",
"url": "https://chromium.googlesource.com/infra/infra/+/339fc51de1f75fe88e35f02781649ca27c2b6977"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "339fc51de1f75fe88e35f02781649ca27c2b6977",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8973602519147444688"
},
{
"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"
}
}
[22:19:35.959] Scheduled build:
{
"id": "8808172457257328593",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 22931,
"createdBy": "project:infra",
"createTime": "2022-07-19T22:19:35.244168081Z",
"updateTime": "2022-07-19T22:19:35.244168081Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "339fc51de1f75fe88e35f02781649ca27c2b6977",
"ref": "refs/heads/main"
}
}
}
[22:19:35.959] Task URL: https://cr-buildbucket-dev.appspot.com/build/8808172457257328593
[22:19:35.959] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:2:0) after 7m47s
[22:27:22.983] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:2:0)
[22:27:23.030] Build status: SCHEDULED
[22:27:23.030] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:3:0) after 5m10s
[22:32:33.057] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:3:0)
[22:32:33.087] Build status: SCHEDULED
[22:32:33.087] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:4:0) after 2m0s
[22:34:33.107] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:4:0)
[22:34:33.130] Build status: SCHEDULED
[22:34:33.130] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:5:0) after 4m33s
[22:39:06.159] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:5:0)
[22:39:06.210] Build status: SCHEDULED
[22:39:06.210] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:6:0) after 1m35s
[22:40:41.229] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:6:0)
[22:40:41.275] Build status: SCHEDULED
[22:40:41.275] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:7:0) after 7m30s
[22:48:11.837] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:7:0)
[22:48:11.886] Build status: SCHEDULED
[22:48:11.886] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:8:0) after 6m1s
[22:54:12.967] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:8:0)
[22:54:12.997] Build status: SCHEDULED
[22:54:12.997] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:9:0) after 1m19s
[22:55:32.014] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:9:0)
[22:55:32.090] Build status: SCHEDULED
[22:55:32.090] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:10:0) after 2m55s
[22:58:27.143] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:10:0)
[22:58:27.215] Build status: SCHEDULED
[22:58:27.215] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:11:0) after 7m16s
[23:05:43.234] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:11:0)
[23:05:43.262] Build status: SCHEDULED
[23:05:43.262] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:12:0) after 2m3s
[23:07:46.283] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:12:0)
[23:07:46.336] Build status: SCHEDULED
[23:07:46.336] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:13:0) after 2m41s
[23:10:27.381] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:13:0)
[23:10:27.420] Build status: SCHEDULED
[23:10:27.420] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:14:0) after 3m53s
[23:14:20.442] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:14:0)
[23:14:20.468] Build status: SCHEDULED
[23:14:20.468] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:15:0) after 5m6s
[23:19:26.491] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:15:0)
[23:19:26.540] Build status: SCHEDULED
[23:19:26.540] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:16:0) after 2m12s
[23:21:38.568] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:16:0)
[23:21:38.713] Build status: SCHEDULED
[23:21:38.713] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:17:0) after 8m21s
[23:29:59.738] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:17:0)
[23:29:59.755] Build status: SCHEDULED
[23:29:59.755] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:18:0) after 8m25s
[23:38:24.879] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:18:0)
[23:38:24.906] Build status: SCHEDULED
[23:38:24.906] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:19:0) after 3m10s
[23:41:34.949] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:19:0)
[23:41:35.042] Build status: SCHEDULED
[23:41:35.042] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:20:0) after 7m24s
[23:44:09.634] Received PubSub notification, asking Buildbucket for the build status
[23:44:09.683] Build status: STARTED
[23:48:59.076] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:20:0)
[23:48:59.163] Build status: STARTED
[23:48:59.163] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8973602519147444688:22:0) after 9m5s
[23:56:36.942] Received PubSub notification, asking Buildbucket for the build status
[23:56:37.065] Build:
{
"id": "8808172457257328593",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 22931,
"createdBy": "project:infra",
"createTime": "2022-07-19T22:19:35.244168081Z",
"startTime": "2022-07-19T23:44:08.344309Z",
"endTime": "2022-07-19T23:56:36.646146814Z",
"updateTime": "2022-07-19T23:56:36.646146814Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "339fc51de1f75fe88e35f02781649ca27c2b6977",
"ref": "refs/heads/main"
}
}
}
[23:56:37.065] Invocation finished in 1h37m3.006116374s with status SUCCEEDED