[23:02:32.084] New invocation is queued and will start shortly
[23:02:33.191] Starting the invocation (attempt 1)
[23:02:33.233] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/56b305c4a39ccd3ece9ab0a9af79dd976c1feeed"
[23:02:33.233] Popped gitiles commit info from properties and tags
[23:02:33.233] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[23:02:33.235] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[23:02:33.236] Buildbucket request:
{
"requestId": "8979941603557752656",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8979941603557752656",
"job": "infra/infra-continuous-win10-64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "cb2d631b103218e3ee97135a64e30e7ca7cbf68e"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@cb2d631b103218e3ee97135a64e30e7ca7cbf68e",
"title": "cb2d631b103218e3ee97135a64e30e7ca7cbf68e",
"url": "https://chromium.googlesource.com/infra/infra/+/cb2d631b103218e3ee97135a64e30e7ca7cbf68e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "555d5a2e50b980412f8fc63611a0c2c51ed68ec4"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@555d5a2e50b980412f8fc63611a0c2c51ed68ec4",
"title": "555d5a2e50b980412f8fc63611a0c2c51ed68ec4",
"url": "https://chromium.googlesource.com/infra/infra/+/555d5a2e50b980412f8fc63611a0c2c51ed68ec4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "7ad7983143e381589257de382ee6eff2284b1033"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@7ad7983143e381589257de382ee6eff2284b1033",
"title": "7ad7983143e381589257de382ee6eff2284b1033",
"url": "https://chromium.googlesource.com/infra/infra/+/7ad7983143e381589257de382ee6eff2284b1033"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "32ab8775259f154df49c476b190bbef809c0905f"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@32ab8775259f154df49c476b190bbef809c0905f",
"title": "32ab8775259f154df49c476b190bbef809c0905f",
"url": "https://chromium.googlesource.com/infra/infra/+/32ab8775259f154df49c476b190bbef809c0905f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "8a5968fbf052df759482dda8f70ed66f68224993"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@8a5968fbf052df759482dda8f70ed66f68224993",
"title": "8a5968fbf052df759482dda8f70ed66f68224993",
"url": "https://chromium.googlesource.com/infra/infra/+/8a5968fbf052df759482dda8f70ed66f68224993"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "95e6709f50c3a725b74e22b5c03af314c75c376a"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@95e6709f50c3a725b74e22b5c03af314c75c376a",
"title": "95e6709f50c3a725b74e22b5c03af314c75c376a",
"url": "https://chromium.googlesource.com/infra/infra/+/95e6709f50c3a725b74e22b5c03af314c75c376a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "2019db59f83e3618d4676ab97322c068a5a46abc"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@2019db59f83e3618d4676ab97322c068a5a46abc",
"title": "2019db59f83e3618d4676ab97322c068a5a46abc",
"url": "https://chromium.googlesource.com/infra/infra/+/2019db59f83e3618d4676ab97322c068a5a46abc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "c825301e347d14a3236cf01b1c53e928972a7a78"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@c825301e347d14a3236cf01b1c53e928972a7a78",
"title": "c825301e347d14a3236cf01b1c53e928972a7a78",
"url": "https://chromium.googlesource.com/infra/infra/+/c825301e347d14a3236cf01b1c53e928972a7a78"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "075b068735192d5f1a32830d460940c5c5013508"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@075b068735192d5f1a32830d460940c5c5013508",
"title": "075b068735192d5f1a32830d460940c5c5013508",
"url": "https://chromium.googlesource.com/infra/infra/+/075b068735192d5f1a32830d460940c5c5013508"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "354d8b0eeffefb8741cd67d291b7298912952711"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@354d8b0eeffefb8741cd67d291b7298912952711",
"title": "354d8b0eeffefb8741cd67d291b7298912952711",
"url": "https://chromium.googlesource.com/infra/infra/+/354d8b0eeffefb8741cd67d291b7298912952711"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "4b0479ea7390208152a3fd7e3acb44165778e677"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@4b0479ea7390208152a3fd7e3acb44165778e677",
"title": "4b0479ea7390208152a3fd7e3acb44165778e677",
"url": "https://chromium.googlesource.com/infra/infra/+/4b0479ea7390208152a3fd7e3acb44165778e677"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "7e64fde997b8bb39d7e85d989b3d0a541ff7658f"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@7e64fde997b8bb39d7e85d989b3d0a541ff7658f",
"title": "7e64fde997b8bb39d7e85d989b3d0a541ff7658f",
"url": "https://chromium.googlesource.com/infra/infra/+/7e64fde997b8bb39d7e85d989b3d0a541ff7658f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "cb1368567d1e83239c7f4a6a188465375c0699c2"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@cb1368567d1e83239c7f4a6a188465375c0699c2",
"title": "cb1368567d1e83239c7f4a6a188465375c0699c2",
"url": "https://chromium.googlesource.com/infra/infra/+/cb1368567d1e83239c7f4a6a188465375c0699c2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "5cccfad3489fc29e1623a72f15428da31cdce884"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@5cccfad3489fc29e1623a72f15428da31cdce884",
"title": "5cccfad3489fc29e1623a72f15428da31cdce884",
"url": "https://chromium.googlesource.com/infra/infra/+/5cccfad3489fc29e1623a72f15428da31cdce884"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "3b78a69c495fc5901f6092cda4763741b92f73c1"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@3b78a69c495fc5901f6092cda4763741b92f73c1",
"title": "3b78a69c495fc5901f6092cda4763741b92f73c1",
"url": "https://chromium.googlesource.com/infra/infra/+/3b78a69c495fc5901f6092cda4763741b92f73c1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "e45a69946e222a22d0edb4179f6a90a1a1a50972"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@e45a69946e222a22d0edb4179f6a90a1a1a50972",
"title": "e45a69946e222a22d0edb4179f6a90a1a1a50972",
"url": "https://chromium.googlesource.com/infra/infra/+/e45a69946e222a22d0edb4179f6a90a1a1a50972"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "2dd10e1dbc2bdfbac6cf07ef1b881937dc5f153e"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@2dd10e1dbc2bdfbac6cf07ef1b881937dc5f153e",
"title": "2dd10e1dbc2bdfbac6cf07ef1b881937dc5f153e",
"url": "https://chromium.googlesource.com/infra/infra/+/2dd10e1dbc2bdfbac6cf07ef1b881937dc5f153e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "559121324452e539cb0a0e57075743419fa17312"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@559121324452e539cb0a0e57075743419fa17312",
"title": "559121324452e539cb0a0e57075743419fa17312",
"url": "https://chromium.googlesource.com/infra/infra/+/559121324452e539cb0a0e57075743419fa17312"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "d0a963c1f89a0328ce2775965b0c14dac8f84b61"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@d0a963c1f89a0328ce2775965b0c14dac8f84b61",
"title": "d0a963c1f89a0328ce2775965b0c14dac8f84b61",
"url": "https://chromium.googlesource.com/infra/infra/+/d0a963c1f89a0328ce2775965b0c14dac8f84b61"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "32c4479b720942291f90417687434b1f1a610ae5"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@32c4479b720942291f90417687434b1f1a610ae5",
"title": "32c4479b720942291f90417687434b1f1a610ae5",
"url": "https://chromium.googlesource.com/infra/infra/+/32c4479b720942291f90417687434b1f1a610ae5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "e37f9ec52974f4125929d7b04b9664e1741763ea"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@e37f9ec52974f4125929d7b04b9664e1741763ea",
"title": "e37f9ec52974f4125929d7b04b9664e1741763ea",
"url": "https://chromium.googlesource.com/infra/infra/+/e37f9ec52974f4125929d7b04b9664e1741763ea"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "56b305c4a39ccd3ece9ab0a9af79dd976c1feeed"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@56b305c4a39ccd3ece9ab0a9af79dd976c1feeed",
"title": "56b305c4a39ccd3ece9ab0a9af79dd976c1feeed",
"url": "https://chromium.googlesource.com/infra/infra/+/56b305c4a39ccd3ece9ab0a9af79dd976c1feeed"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "56b305c4a39ccd3ece9ab0a9af79dd976c1feeed",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8979941603557752656"
},
{
"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"
}
}
[23:02:34.182] Scheduled build:
{
"id": "8814511541605825313",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 22538,
"createdBy": "project:infra",
"createTime": "2022-05-10T23:02:33.312597910Z",
"updateTime": "2022-05-10T23:02:33.312597910Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "56b305c4a39ccd3ece9ab0a9af79dd976c1feeed",
"ref": "refs/heads/main"
}
}
}
[23:02:34.182] Task URL: https://cr-buildbucket-dev.appspot.com/build/8814511541605825313
[23:02:34.182] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:2:0) after 4m17s
[23:06:51.201] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:2:0)
[23:06:51.240] Build status: SCHEDULED
[23:06:51.240] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:3:0) after 2m40s
[23:09:31.362] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:3:0)
[23:09:31.441] Build status: SCHEDULED
[23:09:31.441] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:4:0) after 3m17s
[23:12:48.467] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:4:0)
[23:12:48.527] Build status: SCHEDULED
[23:12:48.527] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:5:0) after 9m49s
[23:22:37.549] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:5:0)
[23:22:37.584] Build status: SCHEDULED
[23:22:37.584] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:6:0) after 1m51s
[23:24:28.611] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:6:0)
[23:24:28.658] Build status: SCHEDULED
[23:24:28.658] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:7:0) after 3m27s
[23:27:55.680] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:7:0)
[23:27:55.743] Build status: SCHEDULED
[23:27:55.743] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:8:0) after 4m19s
[23:32:14.773] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:8:0)
[23:32:14.798] Build status: SCHEDULED
[23:32:14.798] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:9:0) after 8m15s
[23:40:29.820] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:9:0)
[23:40:29.876] Build status: SCHEDULED
[23:40:29.876] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:10:0) after 4m35s
[23:45:04.896] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:10:0)
[23:45:04.932] Build status: SCHEDULED
[23:45:04.932] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:11:0) after 3m32s
[23:48:36.952] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:11:0)
[23:48:37.026] Build status: SCHEDULED
[23:48:37.026] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:12:0) after 1m9s
[23:49:46.051] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:12:0)
[23:49:46.155] Build status: SCHEDULED
[23:49:46.155] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:13:0) after 3m28s
[23:53:14.181] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:13:0)
[23:53:14.228] Build status: SCHEDULED
[23:53:14.228] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:14:0) after 4m19s
[23:57:33.248] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:14:0)
[23:57:33.284] Build status: SCHEDULED
[23:57:33.284] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:15:0) after 4m32s
[00:02:05.303] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:15:0)
[00:02:05.350] Build status: SCHEDULED
[00:02:05.350] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:16:0) after 7m11s
[00:09:16.377] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:16:0)
[00:09:16.410] Build status: SCHEDULED
[00:09:16.410] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:17:0) after 3m9s
[00:12:25.440] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:17:0)
[00:12:25.480] Build status: SCHEDULED
[00:12:25.480] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:18:0) after 8m58s
[00:21:23.510] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:18:0)
[00:21:23.561] Build status: SCHEDULED
[00:21:23.561] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:19:0) after 7m36s
[00:28:59.588] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:19:0)
[00:28:59.628] Build status: SCHEDULED
[00:28:59.628] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:20:0) after 3m51s
[00:32:50.661] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:20:0)
[00:32:50.743] Build status: SCHEDULED
[00:32:50.743] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:21:0) after 1m0s
[00:33:50.765] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:21:0)
[00:33:50.818] Build status: SCHEDULED
[00:33:50.818] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:22:0) after 9m44s
[00:43:34.844] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:22:0)
[00:43:34.897] Build status: SCHEDULED
[00:43:34.897] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:23:0) after 5m21s
[00:48:55.918] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:23:0)
[00:48:55.980] Build status: SCHEDULED
[00:48:55.980] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:24:0) after 8m11s
[00:57:07.013] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:24:0)
[00:57:07.078] Build status: SCHEDULED
[00:57:07.078] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:25:0) after 5m34s
[01:02:41.100] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:25:0)
[01:02:41.225] Build status: SCHEDULED
[01:02:41.225] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:26:0) after 3m57s
[01:06:38.247] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:26:0)
[01:06:38.281] Build status: SCHEDULED
[01:06:38.281] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:27:0) after 1m22s
[01:08:00.847] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:27:0)
[01:08:00.888] Build status: SCHEDULED
[01:08:00.888] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:28:0) after 7m8s
[01:15:08.909] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:28:0)
[01:15:08.977] Build status: SCHEDULED
[01:15:08.977] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:29:0) after 5m30s
[01:20:38.986] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:29:0)
[01:20:39.043] Build status: SCHEDULED
[01:20:39.043] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:30:0) after 4m8s
[01:24:47.064] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:30:0)
[01:24:47.105] Build status: SCHEDULED
[01:24:47.105] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:31:0) after 2m43s
[01:27:30.141] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:31:0)
[01:27:30.266] Build status: SCHEDULED
[01:27:30.266] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:32:0) after 6m5s
[01:33:35.291] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:32:0)
[01:33:35.340] Build status: SCHEDULED
[01:33:35.340] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:33:0) after 2m38s
[01:36:13.361] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:33:0)
[01:36:13.397] Build status: SCHEDULED
[01:36:13.397] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:34:0) after 4m1s
[01:40:14.421] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:34:0)
[01:40:14.458] Build status: SCHEDULED
[01:40:14.458] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:35:0) after 2m9s
[01:42:23.482] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:35:0)
[01:42:23.545] Build status: SCHEDULED
[01:42:23.545] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:36:0) after 1m24s
[01:43:47.569] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:36:0)
[01:43:47.621] Build status: SCHEDULED
[01:43:47.621] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:37:0) after 9m24s
[01:53:11.647] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:37:0)
[01:53:11.685] Build status: SCHEDULED
[01:53:11.685] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:38:0) after 8m3s
[02:01:14.814] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:38:0)
[02:01:14.840] Build status: SCHEDULED
[02:01:14.840] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:39:0) after 9m31s
[02:10:45.863] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:39:0)
[02:10:45.903] Build status: SCHEDULED
[02:10:45.903] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:40:0) after 8m0s
[02:18:45.924] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:40:0)
[02:18:45.956] Build status: SCHEDULED
[02:18:45.956] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:41:0) after 2m19s
[02:21:04.998] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:41:0)
[02:21:05.026] Build status: SCHEDULED
[02:21:05.026] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:42:0) after 7m32s
[02:28:37.049] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:42:0)
[02:28:37.083] Build status: SCHEDULED
[02:28:37.083] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:43:0) after 5m50s
[02:34:27.202] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:43:0)
[02:34:27.244] Build status: SCHEDULED
[02:34:27.244] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:44:0) after 6m55s
[02:41:22.267] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:44:0)
[02:41:22.319] Build status: SCHEDULED
[02:41:22.319] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:45:0) after 7m29s
[02:48:51.342] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:45:0)
[02:48:51.373] Build status: SCHEDULED
[02:48:51.373] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:46:0) after 2m57s
[02:51:48.396] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:46:0)
[02:51:48.485] Build status: SCHEDULED
[02:51:48.485] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:47:0) after 3m0s
[02:54:48.520] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:47:0)
[02:54:48.542] Build status: SCHEDULED
[02:54:48.542] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:48:0) after 7m22s
[03:02:10.569] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:48:0)
[03:02:10.650] Build status: SCHEDULED
[03:02:10.650] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:49:0) after 3m57s
[03:06:07.693] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:49:0)
[03:06:07.743] Build status: SCHEDULED
[03:06:07.743] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:50:0) after 2m57s
[03:09:04.767] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:50:0)
[03:09:04.804] Build status: SCHEDULED
[03:09:04.804] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:51:0) after 5m43s
[03:09:32.716] Received PubSub notification, asking Buildbucket for the build status
[03:09:32.755] Build status: STARTED
[03:14:47.824] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:51:0)
[03:14:47.876] Build status: STARTED
[03:14:47.876] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8979941603557752656:53:0) after 9m30s
[03:24:08.377] Received PubSub notification, asking Buildbucket for the build status
[03:24:08.428] Build:
{
"id": "8814511541605825313",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 22538,
"createdBy": "project:infra",
"createTime": "2022-05-10T23:02:33.312597910Z",
"startTime": "2022-05-11T03:09:30.508687Z",
"endTime": "2022-05-11T03:24:07.836752809Z",
"updateTime": "2022-05-11T03:24:07.836752809Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "56b305c4a39ccd3ece9ab0a9af79dd976c1feeed",
"ref": "refs/heads/main"
}
}
}
[03:24:08.428] Invocation finished in 4h21m36.360546986s with status SUCCEEDED