[00:41:54.126] New invocation is queued and will start shortly
[00:41:55.196] Starting the invocation (attempt 1)
[00:41:55.229] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[00:41:55.229] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[00:41:55.229] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9019798017116643520",
"parameters_json": "{\"builder_name\":\"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\":\"8e25fca941d277e2bfc12f5055271eea20f53817\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8e25fca941d277e2bfc12f5055271eea20f53817\",\"title\":\"8e25fca941d277e2bfc12f5055271eea20f53817\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/8e25fca941d277e2bfc12f5055271eea20f53817\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"b42d97cbcb60fe61cf609fffbafde91e5194d697\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b42d97cbcb60fe61cf609fffbafde91e5194d697\",\"title\":\"b42d97cbcb60fe61cf609fffbafde91e5194d697\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/b42d97cbcb60fe61cf609fffbafde91e5194d697\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"b669c1209b79625e4c8e3728a51a575d07c129b6\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b669c1209b79625e4c8e3728a51a575d07c129b6\",\"title\":\"b669c1209b79625e4c8e3728a51a575d07c129b6\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/b669c1209b79625e4c8e3728a51a575d07c129b6\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"9b677f98102d143513cc4209056e4ecc33456e1f\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@9b677f98102d143513cc4209056e4ecc33456e1f\",\"title\":\"9b677f98102d143513cc4209056e4ecc33456e1f\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/9b677f98102d143513cc4209056e4ecc33456e1f\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"20158dc650dfcbf8c6f65f963bcfc57f36343d67\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@20158dc650dfcbf8c6f65f963bcfc57f36343d67\",\"title\":\"20158dc650dfcbf8c6f65f963bcfc57f36343d67\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/20158dc650dfcbf8c6f65f963bcfc57f36343d67\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"ddb1f580c0c363736cae9cd1b007e2b3ce1a9554\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ddb1f580c0c363736cae9cd1b007e2b3ce1a9554\",\"title\":\"ddb1f580c0c363736cae9cd1b007e2b3ce1a9554\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/ddb1f580c0c363736cae9cd1b007e2b3ce1a9554\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"f8a2ef16e09e42b1c876a0413403804a142b0128\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f8a2ef16e09e42b1c876a0413403804a142b0128\",\"title\":\"f8a2ef16e09e42b1c876a0413403804a142b0128\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/f8a2ef16e09e42b1c876a0413403804a142b0128\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"f8a2ef16e09e42b1c876a0413403804a142b0128\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
},
"tags": [
"builder:infra-continuous-win10-64",
"scheduler_invocation_id:9019798017116643520",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/f8a2ef16e09e42b1c876a0413403804a142b0128",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/f8a2ef16e09e42b1c876a0413403804a142b0128",
"gitiles_ref:refs/heads/master"
]
}
[00:41:55.925] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1614213715335408",
"id": "8854367955187554768",
"parameters_json": "{\"builder_name\": \"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\": \"8e25fca941d277e2bfc12f5055271eea20f53817\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8e25fca941d277e2bfc12f5055271eea20f53817\", \"title\": \"8e25fca941d277e2bfc12f5055271eea20f53817\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/8e25fca941d277e2bfc12f5055271eea20f53817\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b42d97cbcb60fe61cf609fffbafde91e5194d697\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b42d97cbcb60fe61cf609fffbafde91e5194d697\", \"title\": \"b42d97cbcb60fe61cf609fffbafde91e5194d697\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b42d97cbcb60fe61cf609fffbafde91e5194d697\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b669c1209b79625e4c8e3728a51a575d07c129b6\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b669c1209b79625e4c8e3728a51a575d07c129b6\", \"title\": \"b669c1209b79625e4c8e3728a51a575d07c129b6\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b669c1209b79625e4c8e3728a51a575d07c129b6\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"9b677f98102d143513cc4209056e4ecc33456e1f\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@9b677f98102d143513cc4209056e4ecc33456e1f\", \"title\": \"9b677f98102d143513cc4209056e4ecc33456e1f\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/9b677f98102d143513cc4209056e4ecc33456e1f\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"20158dc650dfcbf8c6f65f963bcfc57f36343d67\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@20158dc650dfcbf8c6f65f963bcfc57f36343d67\", \"title\": \"20158dc650dfcbf8c6f65f963bcfc57f36343d67\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/20158dc650dfcbf8c6f65f963bcfc57f36343d67\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ddb1f580c0c363736cae9cd1b007e2b3ce1a9554\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ddb1f580c0c363736cae9cd1b007e2b3ce1a9554\", \"title\": \"ddb1f580c0c363736cae9cd1b007e2b3ce1a9554\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/ddb1f580c0c363736cae9cd1b007e2b3ce1a9554\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f8a2ef16e09e42b1c876a0413403804a142b0128\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f8a2ef16e09e42b1c876a0413403804a142b0128\", \"title\": \"f8a2ef16e09e42b1c876a0413403804a142b0128\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/f8a2ef16e09e42b1c876a0413403804a142b0128\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f8a2ef16e09e42b1c876a0413403804a142b0128\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614213715796575",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18701",
"builder:infra-continuous-win10-64",
"buildset:commit/git/f8a2ef16e09e42b1c876a0413403804a142b0128",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/f8a2ef16e09e42b1c876a0413403804a142b0128",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019798017116643520",
"scheduler_job_id:infra/infra-continuous-win10-64",
"swarming_hostname:chromium-swarm-dev.appspot.com",
"swarming_tag:log_location:logdog://luci-logdog-dev.appspot.com/infra/buildbucket/cr-buildbucket-dev.appspot.com/8854367955187554768/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:infra_continuous",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1614213715796846",
"url": "https://luci-milo-dev.appspot.com/b/8854367955187554768",
"utcnow_ts": "1614213715918352"
}
}
[00:41:55.925] Task URL: https://luci-milo-dev.appspot.com/b/8854367955187554768
[00:41:55.925] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:2:0) after 1m0s
[00:42:55.986] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:2:0)
[00:42:55.986] Timer tick, asking Buildbucket for the build status
[00:42:56.072] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:56.072] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:3:0) after 1m0s
[00:43:56.183] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:3:0)
[00:43:56.183] Timer tick, asking Buildbucket for the build status
[00:43:56.474] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:56.474] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:4:0) after 1m0s
[00:44:56.494] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:4:0)
[00:44:56.494] Timer tick, asking Buildbucket for the build status
[00:44:56.582] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:56.582] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:5:0) after 1m0s
[00:45:56.616] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:5:0)
[00:45:56.616] Timer tick, asking Buildbucket for the build status
[00:45:56.700] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:56.700] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:6:0) after 1m0s
[00:46:56.717] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:6:0)
[00:46:56.717] Timer tick, asking Buildbucket for the build status
[00:46:56.790] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:56.790] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:7:0) after 1m0s
[00:47:56.876] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:7:0)
[00:47:56.876] Timer tick, asking Buildbucket for the build status
[00:47:56.966] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:56.966] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:8:0) after 1m0s
[00:48:56.985] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:8:0)
[00:48:56.985] Timer tick, asking Buildbucket for the build status
[00:48:57.078] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:57.078] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:9:0) after 1m0s
[00:49:57.183] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:9:0)
[00:49:57.183] Timer tick, asking Buildbucket for the build status
[00:49:57.260] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:57.260] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:10:0) after 1m0s
[00:50:57.277] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:10:0)
[00:50:57.277] Timer tick, asking Buildbucket for the build status
[00:50:57.397] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:57.397] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:11:0) after 1m0s
[00:51:57.478] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:11:0)
[00:51:57.478] Timer tick, asking Buildbucket for the build status
[00:51:57.586] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:57.586] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:12:0) after 1m0s
[00:52:57.688] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:12:0)
[00:52:57.688] Timer tick, asking Buildbucket for the build status
[00:52:57.769] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:57.770] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:13:0) after 1m0s
[00:53:57.884] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:13:0)
[00:53:57.884] Timer tick, asking Buildbucket for the build status
[00:53:57.974] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:57.974] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:14:0) after 1m0s
[00:54:58.077] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:14:0)
[00:54:58.077] Timer tick, asking Buildbucket for the build status
[00:54:58.159] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:58.159] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:15:0) after 1m0s
[00:55:58.208] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:15:0)
[00:55:58.208] Timer tick, asking Buildbucket for the build status
[00:55:58.309] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:58.309] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:16:0) after 1m0s
[00:56:58.331] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:16:0)
[00:56:58.331] Timer tick, asking Buildbucket for the build status
[00:56:58.410] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:58.410] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:17:0) after 1m0s
[00:57:58.430] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:17:0)
[00:57:58.430] Timer tick, asking Buildbucket for the build status
[00:57:58.521] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:58.521] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:18:0) after 1m0s
[00:58:58.588] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:18:0)
[00:58:58.588] Timer tick, asking Buildbucket for the build status
[00:58:58.685] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:58.685] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:19:0) after 1m0s
[00:59:58.780] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:19:0)
[00:59:58.780] Timer tick, asking Buildbucket for the build status
[00:59:58.862] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:58.862] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:20:0) after 1m0s
[01:00:58.886] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:20:0)
[01:00:58.886] Timer tick, asking Buildbucket for the build status
[01:00:58.982] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:58.982] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:21:0) after 1m0s
[01:01:59.078] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:21:0)
[01:01:59.078] Timer tick, asking Buildbucket for the build status
[01:01:59.180] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:59.180] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:22:0) after 1m0s
[01:02:59.278] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:22:0)
[01:02:59.278] Timer tick, asking Buildbucket for the build status
[01:02:59.359] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:59.359] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:23:0) after 1m0s
[01:03:59.399] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:23:0)
[01:03:59.399] Timer tick, asking Buildbucket for the build status
[01:03:59.513] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:59.513] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:24:0) after 1m0s
[01:04:59.585] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:24:0)
[01:04:59.585] Timer tick, asking Buildbucket for the build status
[01:04:59.675] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:59.675] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:25:0) after 1m0s
[01:05:59.786] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:25:0)
[01:05:59.786] Timer tick, asking Buildbucket for the build status
[01:05:59.883] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:59.883] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:26:0) after 1m0s
[01:06:59.985] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:26:0)
[01:06:59.985] Timer tick, asking Buildbucket for the build status
[01:07:00.078] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:00.078] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:27:0) after 1m0s
[01:08:00.177] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:27:0)
[01:08:00.177] Timer tick, asking Buildbucket for the build status
[01:08:00.267] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:00.267] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:28:0) after 1m0s
[01:09:00.378] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:28:0)
[01:09:00.378] Timer tick, asking Buildbucket for the build status
[01:09:00.507] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:00.507] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:29:0) after 1m0s
[01:10:00.587] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:29:0)
[01:10:00.587] Timer tick, asking Buildbucket for the build status
[01:10:00.670] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:00.670] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:30:0) after 1m0s
[01:11:00.691] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:30:0)
[01:11:00.691] Timer tick, asking Buildbucket for the build status
[01:11:00.817] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:00.817] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:31:0) after 1m0s
[01:12:00.846] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:31:0)
[01:12:00.846] Timer tick, asking Buildbucket for the build status
[01:12:00.955] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:00.955] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:32:0) after 1m0s
[01:13:00.980] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:32:0)
[01:13:00.980] Timer tick, asking Buildbucket for the build status
[01:13:01.081] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:01.081] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:33:0) after 1m0s
[01:14:01.104] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:33:0)
[01:14:01.104] Timer tick, asking Buildbucket for the build status
[01:14:01.206] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:01.206] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:34:0) after 1m0s
[01:15:01.238] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:34:0)
[01:15:01.238] Timer tick, asking Buildbucket for the build status
[01:15:01.331] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:01.331] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:35:0) after 1m0s
[01:16:01.391] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:35:0)
[01:16:01.391] Timer tick, asking Buildbucket for the build status
[01:16:01.472] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:01.472] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:36:0) after 1m0s
[01:17:01.514] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:36:0)
[01:17:01.514] Timer tick, asking Buildbucket for the build status
[01:17:01.607] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:01.607] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:37:0) after 1m0s
[01:18:01.679] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:37:0)
[01:18:01.679] Timer tick, asking Buildbucket for the build status
[01:18:01.775] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:01.775] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:38:0) after 1m0s
[01:19:01.797] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:38:0)
[01:19:01.797] Timer tick, asking Buildbucket for the build status
[01:19:02.048] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:02.048] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:39:0) after 1m0s
[01:20:02.071] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:39:0)
[01:20:02.071] Timer tick, asking Buildbucket for the build status
[01:20:02.215] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:02.215] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:40:0) after 1m0s
[01:21:02.232] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:40:0)
[01:21:02.232] Timer tick, asking Buildbucket for the build status
[01:21:02.426] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:02.426] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:41:0) after 1m0s
[01:22:02.457] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:41:0)
[01:22:02.457] Timer tick, asking Buildbucket for the build status
[01:22:02.539] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:02.539] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:42:0) after 1m0s
[01:23:02.580] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:42:0)
[01:23:02.581] Timer tick, asking Buildbucket for the build status
[01:23:02.669] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:02.669] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:43:0) after 1m0s
[01:24:02.693] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:43:0)
[01:24:02.693] Timer tick, asking Buildbucket for the build status
[01:24:02.798] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:02.798] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:44:0) after 1m0s
[01:25:02.826] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:44:0)
[01:25:02.826] Timer tick, asking Buildbucket for the build status
[01:25:02.931] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:02.931] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:45:0) after 1m0s
[01:26:02.991] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:45:0)
[01:26:02.991] Timer tick, asking Buildbucket for the build status
[01:26:03.094] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:03.094] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:46:0) after 1m0s
[01:27:03.122] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:46:0)
[01:27:03.122] Timer tick, asking Buildbucket for the build status
[01:27:03.418] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:27:03.418] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:47:0) after 1m0s
[01:28:03.491] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:47:0)
[01:28:03.491] Timer tick, asking Buildbucket for the build status
[01:28:03.640] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:28:03.640] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:48:0) after 1m0s
[01:29:03.668] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:48:0)
[01:29:03.668] Timer tick, asking Buildbucket for the build status
[01:29:03.750] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:29:03.750] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:49:0) after 1m0s
[01:30:03.786] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:49:0)
[01:30:03.786] Timer tick, asking Buildbucket for the build status
[01:30:03.888] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:30:03.888] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:50:0) after 1m0s
[01:31:03.993] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:50:0)
[01:31:03.993] Timer tick, asking Buildbucket for the build status
[01:31:04.108] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:31:04.108] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:51:0) after 1m0s
[01:32:04.133] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:51:0)
[01:32:04.133] Timer tick, asking Buildbucket for the build status
[01:32:04.242] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:32:04.242] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:52:0) after 1m0s
[01:33:04.401] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:52:0)
[01:33:04.401] Timer tick, asking Buildbucket for the build status
[01:33:04.560] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:33:04.560] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:53:0) after 1m0s
[01:34:04.703] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:53:0)
[01:34:04.703] Timer tick, asking Buildbucket for the build status
[01:34:04.791] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:34:04.791] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:54:0) after 1m0s
[01:35:04.846] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:54:0)
[01:35:04.846] Timer tick, asking Buildbucket for the build status
[01:35:04.954] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:35:04.954] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:55:0) after 1m0s
[01:36:05.099] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:55:0)
[01:36:05.099] Timer tick, asking Buildbucket for the build status
[01:36:05.203] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:36:05.203] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:56:0) after 1m0s
[01:37:05.399] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:56:0)
[01:37:05.399] Timer tick, asking Buildbucket for the build status
[01:37:05.504] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:37:05.504] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:57:0) after 1m0s
[01:38:05.528] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:57:0)
[01:38:05.528] Timer tick, asking Buildbucket for the build status
[01:38:05.648] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:38:05.648] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:58:0) after 1m0s
[01:39:05.785] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:58:0)
[01:39:05.785] Timer tick, asking Buildbucket for the build status
[01:39:05.904] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:39:05.904] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:59:0) after 1m0s
[01:40:05.996] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:59:0)
[01:40:05.996] Timer tick, asking Buildbucket for the build status
[01:40:06.086] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:40:06.086] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:60:0) after 1m0s
[01:41:06.109] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:60:0)
[01:41:06.109] Timer tick, asking Buildbucket for the build status
[01:41:06.205] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:41:06.205] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:61:0) after 1m0s
[01:42:06.416] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:61:0)
[01:42:06.416] Timer tick, asking Buildbucket for the build status
[01:42:06.504] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:42:06.504] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:62:0) after 1m0s
[01:43:06.542] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:62:0)
[01:43:06.542] Timer tick, asking Buildbucket for the build status
[01:43:06.635] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:43:06.635] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:63:0) after 1m0s
[01:44:06.655] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:63:0)
[01:44:06.655] Timer tick, asking Buildbucket for the build status
[01:44:06.751] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:44:06.751] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:64:0) after 1m0s
[01:45:06.831] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:64:0)
[01:45:06.831] Timer tick, asking Buildbucket for the build status
[01:45:06.924] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:45:06.924] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:65:0) after 1m0s
[01:46:07.001] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:65:0)
[01:46:07.001] Timer tick, asking Buildbucket for the build status
[01:46:07.102] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:46:07.102] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:66:0) after 1m0s
[01:47:07.177] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:66:0)
[01:47:07.177] Timer tick, asking Buildbucket for the build status
[01:47:07.277] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:47:07.277] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:67:0) after 1m0s
[01:48:07.376] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:67:0)
[01:48:07.376] Timer tick, asking Buildbucket for the build status
[01:48:07.455] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:48:07.455] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:68:0) after 1m0s
[01:49:07.577] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:68:0)
[01:49:07.577] Timer tick, asking Buildbucket for the build status
[01:49:07.667] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:07.667] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:69:0) after 1m0s
[01:50:07.701] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:69:0)
[01:50:07.701] Timer tick, asking Buildbucket for the build status
[01:50:07.789] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:50:07.789] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:70:0) after 1m0s
[01:51:07.830] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:70:0)
[01:51:07.830] Timer tick, asking Buildbucket for the build status
[01:51:07.909] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:51:07.909] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:71:0) after 1m0s
[01:52:07.991] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:71:0)
[01:52:07.991] Timer tick, asking Buildbucket for the build status
[01:52:08.087] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:52:08.087] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:72:0) after 1m0s
[01:53:08.117] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:72:0)
[01:53:08.117] Timer tick, asking Buildbucket for the build status
[01:53:08.243] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:53:08.243] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:73:0) after 1m0s
[01:54:08.380] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:73:0)
[01:54:08.380] Timer tick, asking Buildbucket for the build status
[01:54:08.461] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:54:08.461] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:74:0) after 1m0s
[01:55:08.495] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:74:0)
[01:55:08.495] Timer tick, asking Buildbucket for the build status
[01:55:08.582] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:55:08.582] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:75:0) after 1m0s
[01:56:08.702] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:75:0)
[01:56:08.702] Timer tick, asking Buildbucket for the build status
[01:56:08.794] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:56:08.794] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:76:0) after 1m0s
[01:57:08.904] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:76:0)
[01:57:08.904] Timer tick, asking Buildbucket for the build status
[01:57:09.016] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:57:09.016] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:77:0) after 1m0s
[01:58:09.100] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:77:0)
[01:58:09.100] Timer tick, asking Buildbucket for the build status
[01:58:09.233] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:58:09.233] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:78:0) after 1m0s
[01:59:09.388] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:78:0)
[01:59:09.388] Timer tick, asking Buildbucket for the build status
[01:59:09.479] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:59:09.479] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:79:0) after 1m0s
[02:00:09.518] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:79:0)
[02:00:09.518] Timer tick, asking Buildbucket for the build status
[02:00:09.608] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:00:09.608] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:80:0) after 1m0s
[02:01:09.685] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:80:0)
[02:01:09.685] Timer tick, asking Buildbucket for the build status
[02:01:09.786] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:01:09.786] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:81:0) after 1m0s
[02:02:09.888] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:81:0)
[02:02:09.888] Timer tick, asking Buildbucket for the build status
[02:02:09.973] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:02:09.973] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:82:0) after 1m0s
[02:03:10.095] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:82:0)
[02:03:10.095] Timer tick, asking Buildbucket for the build status
[02:03:10.196] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:03:10.196] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:83:0) after 1m0s
[02:04:10.276] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:83:0)
[02:04:10.276] Timer tick, asking Buildbucket for the build status
[02:04:10.364] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:04:10.364] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:84:0) after 1m0s
[02:05:10.493] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:84:0)
[02:05:10.493] Timer tick, asking Buildbucket for the build status
[02:05:10.615] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:05:10.615] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:85:0) after 1m0s
[02:06:10.691] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:85:0)
[02:06:10.691] Timer tick, asking Buildbucket for the build status
[02:06:10.776] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:06:10.776] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:86:0) after 1m0s
[02:07:10.819] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:86:0)
[02:07:10.819] Timer tick, asking Buildbucket for the build status
[02:07:11.001] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:07:11.001] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:87:0) after 1m0s
[02:08:11.077] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:87:0)
[02:08:11.077] Timer tick, asking Buildbucket for the build status
[02:08:11.172] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:08:11.172] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:88:0) after 1m0s
[02:09:11.284] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:88:0)
[02:09:11.284] Timer tick, asking Buildbucket for the build status
[02:09:11.372] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:09:11.372] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:89:0) after 1m0s
[02:10:11.489] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:89:0)
[02:10:11.489] Timer tick, asking Buildbucket for the build status
[02:10:11.572] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:10:11.572] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:90:0) after 1m0s
[02:11:11.696] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:90:0)
[02:11:11.696] Timer tick, asking Buildbucket for the build status
[02:11:11.794] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:11:11.794] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:91:0) after 1m0s
[02:12:11.901] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:91:0)
[02:12:11.901] Timer tick, asking Buildbucket for the build status
[02:12:11.998] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:12:11.998] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:92:0) after 1m0s
[02:13:12.094] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:92:0)
[02:13:12.094] Timer tick, asking Buildbucket for the build status
[02:13:12.187] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:13:12.187] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:93:0) after 1m0s
[02:14:12.294] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:93:0)
[02:14:12.294] Timer tick, asking Buildbucket for the build status
[02:14:12.393] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:14:12.393] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:94:0) after 1m0s
[02:15:12.414] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:94:0)
[02:15:12.414] Timer tick, asking Buildbucket for the build status
[02:15:12.505] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:15:12.505] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:95:0) after 1m0s
[02:16:12.534] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:95:0)
[02:16:12.534] Timer tick, asking Buildbucket for the build status
[02:16:12.623] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:16:12.623] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:96:0) after 1m0s
[02:17:12.661] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:96:0)
[02:17:12.661] Timer tick, asking Buildbucket for the build status
[02:17:12.874] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:17:12.874] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:97:0) after 1m0s
[02:18:12.996] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:97:0)
[02:18:12.996] Timer tick, asking Buildbucket for the build status
[02:18:13.268] Build 8854367955187554768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:18:13.269] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:98:0) after 1m0s
[02:18:36.242] Received PubSub notification, asking Buildbucket for the build status
[02:18:36.343] Build 8854367955187554768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:13.380] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:98:0)
[02:19:13.380] Timer tick, asking Buildbucket for the build status
[02:19:13.639] Build 8854367955187554768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:13.639] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:100:0) after 1m0s
[02:20:13.698] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:100:0)
[02:20:13.698] Timer tick, asking Buildbucket for the build status
[02:20:13.900] Build 8854367955187554768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:13.900] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:101:0) after 1m0s
[02:21:13.990] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:101:0)
[02:21:13.990] Timer tick, asking Buildbucket for the build status
[02:21:14.415] Build 8854367955187554768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:14.415] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:102:0) after 1m0s
[02:22:14.523] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:102:0)
[02:22:14.523] Timer tick, asking Buildbucket for the build status
[02:22:14.625] Build 8854367955187554768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:14.625] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:103:0) after 1m0s
[02:23:14.688] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:103:0)
[02:23:14.688] Timer tick, asking Buildbucket for the build status
[02:23:14.966] Build 8854367955187554768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:14.966] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:104:0) after 1m0s
[02:24:15.016] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:104:0)
[02:24:15.016] Timer tick, asking Buildbucket for the build status
[02:24:15.245] Build 8854367955187554768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:15.246] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:105:0) after 1m0s
[02:25:15.274] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:105:0)
[02:25:15.274] Timer tick, asking Buildbucket for the build status
[02:25:15.644] Build 8854367955187554768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:15.644] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019798017116643520:106:0) after 1m0s
[02:25:56.588] Received PubSub notification, asking Buildbucket for the build status
[02:25:56.679] Build 8854367955187554768: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:25:56.679] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1614219955052527",
"created_by": "project:infra",
"created_ts": "1614213715335408",
"id": "8854367955187554768",
"parameters_json": "{\"builder_name\": \"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\": \"8e25fca941d277e2bfc12f5055271eea20f53817\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8e25fca941d277e2bfc12f5055271eea20f53817\", \"title\": \"8e25fca941d277e2bfc12f5055271eea20f53817\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/8e25fca941d277e2bfc12f5055271eea20f53817\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b42d97cbcb60fe61cf609fffbafde91e5194d697\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b42d97cbcb60fe61cf609fffbafde91e5194d697\", \"title\": \"b42d97cbcb60fe61cf609fffbafde91e5194d697\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b42d97cbcb60fe61cf609fffbafde91e5194d697\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b669c1209b79625e4c8e3728a51a575d07c129b6\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b669c1209b79625e4c8e3728a51a575d07c129b6\", \"title\": \"b669c1209b79625e4c8e3728a51a575d07c129b6\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b669c1209b79625e4c8e3728a51a575d07c129b6\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"9b677f98102d143513cc4209056e4ecc33456e1f\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@9b677f98102d143513cc4209056e4ecc33456e1f\", \"title\": \"9b677f98102d143513cc4209056e4ecc33456e1f\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/9b677f98102d143513cc4209056e4ecc33456e1f\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"20158dc650dfcbf8c6f65f963bcfc57f36343d67\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@20158dc650dfcbf8c6f65f963bcfc57f36343d67\", \"title\": \"20158dc650dfcbf8c6f65f963bcfc57f36343d67\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/20158dc650dfcbf8c6f65f963bcfc57f36343d67\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ddb1f580c0c363736cae9cd1b007e2b3ce1a9554\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ddb1f580c0c363736cae9cd1b007e2b3ce1a9554\", \"title\": \"ddb1f580c0c363736cae9cd1b007e2b3ce1a9554\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/ddb1f580c0c363736cae9cd1b007e2b3ce1a9554\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f8a2ef16e09e42b1c876a0413403804a142b0128\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f8a2ef16e09e42b1c876a0413403804a142b0128\", \"title\": \"f8a2ef16e09e42b1c876a0413403804a142b0128\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/f8a2ef16e09e42b1c876a0413403804a142b0128\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f8a2ef16e09e42b1c876a0413403804a142b0128\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"f8a2ef16e09e42b1c876a0413403804a142b0128\", \"got_revision_cp\": \"refs/heads/master@{#38801}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_044445a3bc7a4bfe795493d7956aa02cd526172de1fa7300f69fe152e82ea631_v2\", \"builder_5de1032f0e9ef497433aad28840ee258c037976023b410f653133cee0ad04e2c_v2\", \"builder_82ed111fed7ea8169e482f00b5f3e5338a65daa41208585de67cb0a736b1d538_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"builder_d38bdc976f893fc6930479dce8a6d7dc793b2a74fe1392d4af00713a5c0bb46e_v2\", \"git\", \"goma\", \"infra_gclient_with_go\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-n9ce\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5712-339372b\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"started_ts": "1614219512773766",
"status": "COMPLETED",
"status_changed_ts": "1614219513124038",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18701",
"builder:infra-continuous-win10-64",
"buildset:commit/git/f8a2ef16e09e42b1c876a0413403804a142b0128",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/f8a2ef16e09e42b1c876a0413403804a142b0128",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019798017116643520",
"scheduler_job_id:infra/infra-continuous-win10-64",
"swarming_hostname:chromium-swarm-dev.appspot.com",
"swarming_tag:log_location:logdog://luci-logdog-dev.appspot.com/infra/buildbucket/cr-buildbucket-dev.appspot.com/8854367955187554768/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:infra_continuous",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:51ef742074d50010",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1614219955053026",
"url": "https://luci-milo-dev.appspot.com/b/8854367955187554768",
"utcnow_ts": "1614219956670506"
}
}
[02:25:56.679] Invocation finished in 1h44m2.567547023s with status SUCCEEDED