[01:57:02.066] New invocation is queued and will start shortly
[01:57:03.153] Starting the invocation (attempt 1)
[01:57:03.197] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[01:57:03.197] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[01:57:03.197] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9019883887167417664",
"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\":\"d013d972e9dd3741deeefa52810113aaae9d9cef\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d013d972e9dd3741deeefa52810113aaae9d9cef\",\"title\":\"d013d972e9dd3741deeefa52810113aaae9d9cef\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/d013d972e9dd3741deeefa52810113aaae9d9cef\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"e75c9bf286fbb31347379cb478df2a556ab185b1\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@e75c9bf286fbb31347379cb478df2a556ab185b1\",\"title\":\"e75c9bf286fbb31347379cb478df2a556ab185b1\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/e75c9bf286fbb31347379cb478df2a556ab185b1\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"92ab087d938dba75a72e1782678d0a5b319ae020\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@92ab087d938dba75a72e1782678d0a5b319ae020\",\"title\":\"92ab087d938dba75a72e1782678d0a5b319ae020\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/92ab087d938dba75a72e1782678d0a5b319ae020\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"143d1e7a22136cf6f7ee797f99b94bf3baf04e4f\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@143d1e7a22136cf6f7ee797f99b94bf3baf04e4f\",\"title\":\"143d1e7a22136cf6f7ee797f99b94bf3baf04e4f\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/143d1e7a22136cf6f7ee797f99b94bf3baf04e4f\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"559eb37601936ab7246ab9b4cc89620a76c78999\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@559eb37601936ab7246ab9b4cc89620a76c78999\",\"title\":\"559eb37601936ab7246ab9b4cc89620a76c78999\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/559eb37601936ab7246ab9b4cc89620a76c78999\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"5e482e36621630069b402eebc7da01528e95fb86\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@5e482e36621630069b402eebc7da01528e95fb86\",\"title\":\"5e482e36621630069b402eebc7da01528e95fb86\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/5e482e36621630069b402eebc7da01528e95fb86\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"dd599aa47a3be20f9f4c68dc29349b347ae35031\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@dd599aa47a3be20f9f4c68dc29349b347ae35031\",\"title\":\"dd599aa47a3be20f9f4c68dc29349b347ae35031\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/dd599aa47a3be20f9f4c68dc29349b347ae35031\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"1dea81983f5cab79a91f561618226d958d5a6b2f\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1dea81983f5cab79a91f561618226d958d5a6b2f\",\"title\":\"1dea81983f5cab79a91f561618226d958d5a6b2f\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/1dea81983f5cab79a91f561618226d958d5a6b2f\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"1dea81983f5cab79a91f561618226d958d5a6b2f\"}}",
"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:9019883887167417664",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/1dea81983f5cab79a91f561618226d958d5a6b2f",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/1dea81983f5cab79a91f561618226d958d5a6b2f",
"gitiles_ref:refs/heads/master"
]
}
[01:57:03.977] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:infra",
"created_ts": "1614131823293828",
"id": "8854453825216786192",
"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\": \"d013d972e9dd3741deeefa52810113aaae9d9cef\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d013d972e9dd3741deeefa52810113aaae9d9cef\", \"title\": \"d013d972e9dd3741deeefa52810113aaae9d9cef\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/d013d972e9dd3741deeefa52810113aaae9d9cef\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"e75c9bf286fbb31347379cb478df2a556ab185b1\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@e75c9bf286fbb31347379cb478df2a556ab185b1\", \"title\": \"e75c9bf286fbb31347379cb478df2a556ab185b1\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/e75c9bf286fbb31347379cb478df2a556ab185b1\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"92ab087d938dba75a72e1782678d0a5b319ae020\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@92ab087d938dba75a72e1782678d0a5b319ae020\", \"title\": \"92ab087d938dba75a72e1782678d0a5b319ae020\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/92ab087d938dba75a72e1782678d0a5b319ae020\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"143d1e7a22136cf6f7ee797f99b94bf3baf04e4f\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@143d1e7a22136cf6f7ee797f99b94bf3baf04e4f\", \"title\": \"143d1e7a22136cf6f7ee797f99b94bf3baf04e4f\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/143d1e7a22136cf6f7ee797f99b94bf3baf04e4f\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"559eb37601936ab7246ab9b4cc89620a76c78999\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@559eb37601936ab7246ab9b4cc89620a76c78999\", \"title\": \"559eb37601936ab7246ab9b4cc89620a76c78999\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/559eb37601936ab7246ab9b4cc89620a76c78999\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"5e482e36621630069b402eebc7da01528e95fb86\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@5e482e36621630069b402eebc7da01528e95fb86\", \"title\": \"5e482e36621630069b402eebc7da01528e95fb86\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/5e482e36621630069b402eebc7da01528e95fb86\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"dd599aa47a3be20f9f4c68dc29349b347ae35031\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@dd599aa47a3be20f9f4c68dc29349b347ae35031\", \"title\": \"dd599aa47a3be20f9f4c68dc29349b347ae35031\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/dd599aa47a3be20f9f4c68dc29349b347ae35031\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"1dea81983f5cab79a91f561618226d958d5a6b2f\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1dea81983f5cab79a91f561618226d958d5a6b2f\", \"title\": \"1dea81983f5cab79a91f561618226d958d5a6b2f\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/1dea81983f5cab79a91f561618226d958d5a6b2f\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"1dea81983f5cab79a91f561618226d958d5a6b2f\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614131823845117",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18689",
"builder:infra-continuous-win10-64",
"buildset:commit/git/1dea81983f5cab79a91f561618226d958d5a6b2f",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/1dea81983f5cab79a91f561618226d958d5a6b2f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019883887167417664",
"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/8854453825216786192/+/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": "1614131823845255",
"url": "https://luci-milo-dev.appspot.com/b/8854453825216786192",
"utcnow_ts": "1614131823968030"
}
}
[01:57:03.977] Task URL: https://luci-milo-dev.appspot.com/b/8854453825216786192
[01:57:03.977] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:2:0) after 1m0s
[01:58:04.078] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:2:0)
[01:58:04.078] Timer tick, asking Buildbucket for the build status
[01:58:04.179] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:58:04.179] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:3:0) after 1m0s
[01:59:04.213] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:3:0)
[01:59:04.213] Timer tick, asking Buildbucket for the build status
[01:59:04.323] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:59:04.323] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:4:0) after 1m0s
[02:00:04.397] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:4:0)
[02:00:04.397] Timer tick, asking Buildbucket for the build status
[02:00:04.505] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:00:04.505] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:5:0) after 1m0s
[02:01:04.568] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:5:0)
[02:01:04.568] Timer tick, asking Buildbucket for the build status
[02:01:04.724] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:01:04.724] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:6:0) after 1m0s
[02:02:04.823] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:6:0)
[02:02:04.823] Timer tick, asking Buildbucket for the build status
[02:02:04.950] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:02:04.950] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:7:0) after 1m0s
[02:03:05.064] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:7:0)
[02:03:05.064] Timer tick, asking Buildbucket for the build status
[02:03:05.181] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:03:05.181] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:8:0) after 1m0s
[02:04:05.267] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:8:0)
[02:04:05.267] Timer tick, asking Buildbucket for the build status
[02:04:05.402] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:04:05.402] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:9:0) after 1m0s
[02:05:05.427] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:9:0)
[02:05:05.427] Timer tick, asking Buildbucket for the build status
[02:05:05.560] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:05:05.560] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:10:0) after 1m0s
[02:06:05.670] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:10:0)
[02:06:05.670] Timer tick, asking Buildbucket for the build status
[02:06:05.823] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:06:05.823] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:11:0) after 1m0s
[02:07:05.874] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:11:0)
[02:07:05.874] Timer tick, asking Buildbucket for the build status
[02:07:05.999] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:07:05.999] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:12:0) after 1m0s
[02:08:06.025] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:12:0)
[02:08:06.025] Timer tick, asking Buildbucket for the build status
[02:08:06.150] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:08:06.150] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:13:0) after 1m0s
[02:09:06.229] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:13:0)
[02:09:06.229] Timer tick, asking Buildbucket for the build status
[02:09:06.342] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:09:06.342] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:14:0) after 1m0s
[02:10:06.363] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:14:0)
[02:10:06.363] Timer tick, asking Buildbucket for the build status
[02:10:06.510] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:10:06.510] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:15:0) after 1m0s
[02:11:06.542] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:15:0)
[02:11:06.542] Timer tick, asking Buildbucket for the build status
[02:11:06.707] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:11:06.707] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:16:0) after 1m0s
[02:12:06.773] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:16:0)
[02:12:06.773] Timer tick, asking Buildbucket for the build status
[02:12:06.896] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:12:06.896] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:17:0) after 1m0s
[02:13:06.973] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:17:0)
[02:13:06.973] Timer tick, asking Buildbucket for the build status
[02:13:07.126] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:13:07.126] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:18:0) after 1m0s
[02:14:07.158] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:18:0)
[02:14:07.158] Timer tick, asking Buildbucket for the build status
[02:14:07.297] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:14:07.297] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:19:0) after 1m0s
[02:15:07.361] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:19:0)
[02:15:07.361] Timer tick, asking Buildbucket for the build status
[02:15:07.496] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:15:07.496] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:20:0) after 1m0s
[02:16:07.524] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:20:0)
[02:16:07.524] Timer tick, asking Buildbucket for the build status
[02:16:07.684] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:16:07.684] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:21:0) after 1m0s
[02:17:07.718] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:21:0)
[02:17:07.718] Timer tick, asking Buildbucket for the build status
[02:17:07.856] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:17:07.856] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:22:0) after 1m0s
[02:18:07.960] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:22:0)
[02:18:07.960] Timer tick, asking Buildbucket for the build status
[02:18:08.138] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:18:08.138] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:23:0) after 1m0s
[02:19:08.159] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:23:0)
[02:19:08.159] Timer tick, asking Buildbucket for the build status
[02:19:08.281] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:19:08.281] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:24:0) after 1m0s
[02:20:08.311] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:24:0)
[02:20:08.311] Timer tick, asking Buildbucket for the build status
[02:20:08.435] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:20:08.436] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:25:0) after 1m0s
[02:21:08.471] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:25:0)
[02:21:08.471] Timer tick, asking Buildbucket for the build status
[02:21:08.579] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:21:08.579] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:26:0) after 1m0s
[02:22:08.629] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:26:0)
[02:22:08.629] Timer tick, asking Buildbucket for the build status
[02:22:08.877] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:22:08.877] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:27:0) after 1m0s
[02:23:08.958] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:27:0)
[02:23:08.958] Timer tick, asking Buildbucket for the build status
[02:23:09.064] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:23:09.064] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:28:0) after 1m0s
[02:24:09.094] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:28:0)
[02:24:09.094] Timer tick, asking Buildbucket for the build status
[02:24:09.219] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:24:09.219] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:29:0) after 1m0s
[02:25:09.315] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:29:0)
[02:25:09.315] Timer tick, asking Buildbucket for the build status
[02:25:09.491] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:25:09.491] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:30:0) after 1m0s
[02:26:09.511] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:30:0)
[02:26:09.511] Timer tick, asking Buildbucket for the build status
[02:26:09.660] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:26:09.660] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:31:0) after 1m0s
[02:27:09.713] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:31:0)
[02:27:09.713] Timer tick, asking Buildbucket for the build status
[02:27:09.824] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:27:09.824] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:32:0) after 1m0s
[02:28:09.834] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:32:0)
[02:28:09.834] Timer tick, asking Buildbucket for the build status
[02:28:09.952] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:28:09.952] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:33:0) after 1m0s
[02:29:10.011] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:33:0)
[02:29:10.011] Timer tick, asking Buildbucket for the build status
[02:29:10.141] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:29:10.141] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:34:0) after 1m0s
[02:30:10.168] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:34:0)
[02:30:10.168] Timer tick, asking Buildbucket for the build status
[02:30:10.333] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:30:10.333] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:35:0) after 1m0s
[02:31:10.423] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:35:0)
[02:31:10.423] Timer tick, asking Buildbucket for the build status
[02:31:10.541] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:31:10.541] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:36:0) after 1m0s
[02:32:10.562] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:36:0)
[02:32:10.562] Timer tick, asking Buildbucket for the build status
[02:32:10.756] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:32:10.756] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:37:0) after 1m0s
[02:33:10.831] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:37:0)
[02:33:10.831] Timer tick, asking Buildbucket for the build status
[02:33:11.035] Build 8854453825216786192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:33:11.035] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:38:0) after 1m0s
[02:34:09.911] Received PubSub notification, asking Buildbucket for the build status
[02:34:10.011] Build 8854453825216786192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:11.125] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:38:0)
[02:34:11.125] Timer tick, asking Buildbucket for the build status
[02:34:11.306] Build 8854453825216786192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:11.306] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:40:0) after 1m0s
[02:35:11.359] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:40:0)
[02:35:11.359] Timer tick, asking Buildbucket for the build status
[02:35:11.465] Build 8854453825216786192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:11.465] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:41:0) after 1m0s
[02:36:11.487] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:41:0)
[02:36:11.487] Timer tick, asking Buildbucket for the build status
[02:36:11.600] Build 8854453825216786192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:11.600] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:42:0) after 1m0s
[02:37:11.658] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:42:0)
[02:37:11.658] Timer tick, asking Buildbucket for the build status
[02:37:11.802] Build 8854453825216786192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:11.802] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:43:0) after 1m0s
[02:38:11.857] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:43:0)
[02:38:11.857] Timer tick, asking Buildbucket for the build status
[02:38:11.990] Build 8854453825216786192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:11.990] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:44:0) after 1m0s
[02:39:12.036] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:44:0)
[02:39:12.036] Timer tick, asking Buildbucket for the build status
[02:39:12.172] Build 8854453825216786192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:12.172] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:45:0) after 1m0s
[02:40:12.221] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:45:0)
[02:40:12.221] Timer tick, asking Buildbucket for the build status
[02:40:12.400] Build 8854453825216786192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:12.400] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:46:0) after 1m0s
[02:41:12.471] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:46:0)
[02:41:12.471] Timer tick, asking Buildbucket for the build status
[02:41:12.590] Build 8854453825216786192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:12.590] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:47:0) after 1m0s
[02:42:12.669] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:47:0)
[02:42:12.669] Timer tick, asking Buildbucket for the build status
[02:42:12.781] Build 8854453825216786192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:12.781] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019883887167417664:48:0) after 1m0s
[02:42:42.695] Received PubSub notification, asking Buildbucket for the build status
[02:42:42.892] Build 8854453825216786192: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:42:42.892] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1614134561352370",
"created_by": "project:infra",
"created_ts": "1614131823293828",
"id": "8854453825216786192",
"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\": \"d013d972e9dd3741deeefa52810113aaae9d9cef\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d013d972e9dd3741deeefa52810113aaae9d9cef\", \"title\": \"d013d972e9dd3741deeefa52810113aaae9d9cef\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/d013d972e9dd3741deeefa52810113aaae9d9cef\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"e75c9bf286fbb31347379cb478df2a556ab185b1\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@e75c9bf286fbb31347379cb478df2a556ab185b1\", \"title\": \"e75c9bf286fbb31347379cb478df2a556ab185b1\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/e75c9bf286fbb31347379cb478df2a556ab185b1\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"92ab087d938dba75a72e1782678d0a5b319ae020\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@92ab087d938dba75a72e1782678d0a5b319ae020\", \"title\": \"92ab087d938dba75a72e1782678d0a5b319ae020\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/92ab087d938dba75a72e1782678d0a5b319ae020\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"143d1e7a22136cf6f7ee797f99b94bf3baf04e4f\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@143d1e7a22136cf6f7ee797f99b94bf3baf04e4f\", \"title\": \"143d1e7a22136cf6f7ee797f99b94bf3baf04e4f\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/143d1e7a22136cf6f7ee797f99b94bf3baf04e4f\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"559eb37601936ab7246ab9b4cc89620a76c78999\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@559eb37601936ab7246ab9b4cc89620a76c78999\", \"title\": \"559eb37601936ab7246ab9b4cc89620a76c78999\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/559eb37601936ab7246ab9b4cc89620a76c78999\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"5e482e36621630069b402eebc7da01528e95fb86\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@5e482e36621630069b402eebc7da01528e95fb86\", \"title\": \"5e482e36621630069b402eebc7da01528e95fb86\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/5e482e36621630069b402eebc7da01528e95fb86\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"dd599aa47a3be20f9f4c68dc29349b347ae35031\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@dd599aa47a3be20f9f4c68dc29349b347ae35031\", \"title\": \"dd599aa47a3be20f9f4c68dc29349b347ae35031\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/dd599aa47a3be20f9f4c68dc29349b347ae35031\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"1dea81983f5cab79a91f561618226d958d5a6b2f\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1dea81983f5cab79a91f561618226d958d5a6b2f\", \"title\": \"1dea81983f5cab79a91f561618226d958d5a6b2f\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/1dea81983f5cab79a91f561618226d958d5a6b2f\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"1dea81983f5cab79a91f561618226d958d5a6b2f\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"1dea81983f5cab79a91f561618226d958d5a6b2f\", \"got_revision_cp\": \"refs/heads/master@{#38769}\"}, \"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-iy05\"], \"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\": [\"5709-d3c4474\"], \"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": "1614134046130791",
"status": "COMPLETED",
"status_changed_ts": "1614134046480913",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18689",
"builder:infra-continuous-win10-64",
"buildset:commit/git/1dea81983f5cab79a91f561618226d958d5a6b2f",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/1dea81983f5cab79a91f561618226d958d5a6b2f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019883887167417664",
"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/8854453825216786192/+/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:51ea928e2e920f10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1614134561354232",
"url": "https://luci-milo-dev.appspot.com/b/8854453825216786192",
"utcnow_ts": "1614134562882062"
}
}
[02:42:42.892] Invocation finished in 45m40.843087627s with status SUCCEEDED