[00:14:36.103] New invocation is queued and will start shortly
[00:14:37.177] Starting the invocation (attempt 1)
[00:14:37.217] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[00:14:37.219] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[00:14:37.219] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9081133880968335792",
"parameters_json": "{\"builder_name\":\"infra-continuous-win10-64\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"01ab4adc345fdfd8b7e58e2fffdfb87460edee2f\"}}",
"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:9081133880968335792",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/01ab4adc345fdfd8b7e58e2fffdfb87460edee2f",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/01ab4adc345fdfd8b7e58e2fffdfb87460edee2f",
"gitiles_ref:refs/heads/master"
]
}
[00:14:38.616] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "AUTO",
"created_by": "project:infra",
"created_ts": "1555719277349252",
"id": "8915703818992523760",
"parameters_json": "{\"builder_name\": \"infra-continuous-win10-64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"01ab4adc345fdfd8b7e58e2fffdfb87460edee2f\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555719278345931",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/8154",
"builder:infra-continuous-win10-64",
"buildset:commit/git/01ab4adc345fdfd8b7e58e2fffdfb87460edee2f",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/01ab4adc345fdfd8b7e58e2fffdfb87460edee2f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081133880968335792",
"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/8915703818992523760/+/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": "1555719278346067",
"url": "https://luci-milo-dev.appspot.com/b/8915703818992523760",
"utcnow_ts": "1555719278579818"
}
}
[00:14:38.616] Task URL: https://luci-milo-dev.appspot.com/b/8915703818992523760
[00:14:38.616] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:2:0) after 1m0s
[00:15:38.634] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:2:0)
[00:15:38.634] Timer tick, asking Buildbucket for the build status
[00:15:38.881] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:38.881] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:3:0) after 1m0s
[00:16:38.912] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:3:0)
[00:16:38.912] Timer tick, asking Buildbucket for the build status
[00:16:39.097] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:39.097] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:4:0) after 1m0s
[00:17:39.125] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:4:0)
[00:17:39.125] Timer tick, asking Buildbucket for the build status
[00:17:39.372] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:39.372] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:5:0) after 1m0s
[00:18:39.389] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:5:0)
[00:18:39.389] Timer tick, asking Buildbucket for the build status
[00:18:39.494] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:39.494] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:6:0) after 1m0s
[00:19:39.512] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:6:0)
[00:19:39.512] Timer tick, asking Buildbucket for the build status
[00:19:39.604] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:39.604] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:7:0) after 1m0s
[00:20:39.628] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:7:0)
[00:20:39.628] Timer tick, asking Buildbucket for the build status
[00:20:40.592] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:40.592] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:8:0) after 1m0s
[00:21:40.612] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:8:0)
[00:21:40.612] Timer tick, asking Buildbucket for the build status
[00:21:40.907] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:40.907] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:9:0) after 1m0s
[00:22:40.992] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:9:0)
[00:22:40.992] Timer tick, asking Buildbucket for the build status
[00:22:41.292] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:41.292] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:10:0) after 1m0s
[00:23:41.327] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:10:0)
[00:23:41.327] Timer tick, asking Buildbucket for the build status
[00:23:41.605] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:41.605] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:11:0) after 1m0s
[00:24:41.623] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:11:0)
[00:24:41.623] Timer tick, asking Buildbucket for the build status
[00:24:41.752] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:41.752] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:12:0) after 1m0s
[00:25:41.777] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:12:0)
[00:25:41.777] Timer tick, asking Buildbucket for the build status
[00:25:41.905] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:41.905] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:13:0) after 1m0s
[00:26:41.930] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:13:0)
[00:26:41.930] Timer tick, asking Buildbucket for the build status
[00:26:42.050] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:42.050] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:14:0) after 1m0s
[00:27:42.074] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:14:0)
[00:27:42.074] Timer tick, asking Buildbucket for the build status
[00:27:42.154] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:42.154] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:15:0) after 1m0s
[00:28:42.177] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:15:0)
[00:28:42.177] Timer tick, asking Buildbucket for the build status
[00:28:42.285] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:42.285] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:16:0) after 1m0s
[00:29:42.306] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:16:0)
[00:29:42.306] Timer tick, asking Buildbucket for the build status
[00:29:42.421] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:42.421] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:17:0) after 1m0s
[00:30:42.439] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:17:0)
[00:30:42.439] Timer tick, asking Buildbucket for the build status
[00:30:42.537] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:42.537] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:18:0) after 1m0s
[00:31:42.563] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:18:0)
[00:31:42.563] Timer tick, asking Buildbucket for the build status
[00:31:42.665] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:42.666] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:19:0) after 1m0s
[00:32:42.689] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:19:0)
[00:32:42.689] Timer tick, asking Buildbucket for the build status
[00:32:42.927] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:42.927] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:20:0) after 1m0s
[00:33:42.953] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:20:0)
[00:33:42.953] Timer tick, asking Buildbucket for the build status
[00:33:43.058] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:43.058] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:21:0) after 1m0s
[00:34:43.089] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:21:0)
[00:34:43.089] Timer tick, asking Buildbucket for the build status
[00:34:43.261] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:43.261] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:22:0) after 1m0s
[00:35:43.282] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:22:0)
[00:35:43.282] Timer tick, asking Buildbucket for the build status
[00:35:43.362] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:43.362] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:23:0) after 1m0s
[00:36:43.382] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:23:0)
[00:36:43.382] Timer tick, asking Buildbucket for the build status
[00:36:43.469] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:43.469] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:24:0) after 1m0s
[00:37:43.493] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:24:0)
[00:37:43.493] Timer tick, asking Buildbucket for the build status
[00:37:43.617] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:43.617] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:25:0) after 1m0s
[00:38:43.638] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:25:0)
[00:38:43.638] Timer tick, asking Buildbucket for the build status
[00:38:43.747] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:43.747] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:26:0) after 1m0s
[00:39:43.773] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:26:0)
[00:39:43.773] Timer tick, asking Buildbucket for the build status
[00:39:43.839] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:43.839] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:27:0) after 1m0s
[00:40:43.856] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:27:0)
[00:40:43.856] Timer tick, asking Buildbucket for the build status
[00:40:44.059] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:44.059] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:28:0) after 1m0s
[00:41:44.131] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:28:0)
[00:41:44.131] Timer tick, asking Buildbucket for the build status
[00:41:44.219] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:44.219] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:29:0) after 1m0s
[00:42:44.237] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:29:0)
[00:42:44.237] Timer tick, asking Buildbucket for the build status
[00:42:44.597] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:44.597] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:30:0) after 1m0s
[00:43:44.622] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:30:0)
[00:43:44.622] Timer tick, asking Buildbucket for the build status
[00:43:44.857] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:44.857] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:31:0) after 1m0s
[00:44:44.890] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:31:0)
[00:44:44.890] Timer tick, asking Buildbucket for the build status
[00:44:44.978] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:44.978] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:32:0) after 1m0s
[00:45:44.999] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:32:0)
[00:45:44.999] Timer tick, asking Buildbucket for the build status
[00:45:45.113] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:45.113] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:33:0) after 1m0s
[00:46:45.145] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:33:0)
[00:46:45.145] Timer tick, asking Buildbucket for the build status
[00:46:45.325] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:45.325] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:34:0) after 1m0s
[00:47:45.407] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:34:0)
[00:47:45.407] Timer tick, asking Buildbucket for the build status
[00:47:45.577] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:45.577] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:35:0) after 1m0s
[00:48:45.598] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:35:0)
[00:48:45.598] Timer tick, asking Buildbucket for the build status
[00:48:46.280] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:46.280] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:36:0) after 1m0s
[00:49:46.298] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:36:0)
[00:49:46.298] Timer tick, asking Buildbucket for the build status
[00:49:46.406] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:46.406] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:37:0) after 1m0s
[00:50:46.435] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:37:0)
[00:50:46.436] Timer tick, asking Buildbucket for the build status
[00:50:46.553] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:46.553] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:38:0) after 1m0s
[00:51:46.572] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:38:0)
[00:51:46.572] Timer tick, asking Buildbucket for the build status
[00:51:46.696] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:46.696] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:39:0) after 1m0s
[00:52:46.714] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:39:0)
[00:52:46.714] Timer tick, asking Buildbucket for the build status
[00:52:46.849] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:46.849] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:40:0) after 1m0s
[00:53:46.880] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:40:0)
[00:53:46.880] Timer tick, asking Buildbucket for the build status
[00:53:46.986] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:46.986] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:41:0) after 1m0s
[00:54:47.013] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:41:0)
[00:54:47.013] Timer tick, asking Buildbucket for the build status
[00:54:47.199] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:47.199] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:42:0) after 1m0s
[00:55:47.225] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:42:0)
[00:55:47.225] Timer tick, asking Buildbucket for the build status
[00:55:47.356] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:47.356] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:43:0) after 1m0s
[00:56:47.375] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:43:0)
[00:56:47.375] Timer tick, asking Buildbucket for the build status
[00:56:47.562] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:47.562] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:44:0) after 1m0s
[00:57:47.580] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:44:0)
[00:57:47.580] Timer tick, asking Buildbucket for the build status
[00:57:47.721] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:47.721] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:45:0) after 1m0s
[00:58:47.740] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:45:0)
[00:58:47.740] Timer tick, asking Buildbucket for the build status
[00:58:47.927] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:47.927] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:46:0) after 1m0s
[00:59:47.999] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:46:0)
[00:59:47.999] Timer tick, asking Buildbucket for the build status
[00:59:50.186] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:50.186] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:47:0) after 1m0s
[01:00:50.209] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:47:0)
[01:00:50.209] Timer tick, asking Buildbucket for the build status
[01:00:50.334] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:50.334] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:48:0) after 1m0s
[01:01:50.362] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:48:0)
[01:01:50.362] Timer tick, asking Buildbucket for the build status
[01:01:50.497] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:50.497] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:49:0) after 1m0s
[01:02:50.515] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:49:0)
[01:02:50.515] Timer tick, asking Buildbucket for the build status
[01:02:50.685] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:50.685] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:50:0) after 1m0s
[01:03:50.708] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:50:0)
[01:03:50.708] Timer tick, asking Buildbucket for the build status
[01:03:50.799] Build 8915703818992523760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:50.799] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:51:0) after 1m0s
[01:04:47.774] Received PubSub notification, asking Buildbucket for the build status
[01:04:47.876] Build 8915703818992523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:50.912] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:51:0)
[01:04:50.912] Timer tick, asking Buildbucket for the build status
[01:04:50.987] Build 8915703818992523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:50.987] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:53:0) after 1m0s
[01:05:51.007] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:53:0)
[01:05:51.007] Timer tick, asking Buildbucket for the build status
[01:05:51.152] Build 8915703818992523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:51.152] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:54:0) after 1m0s
[01:06:51.176] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:54:0)
[01:06:51.176] Timer tick, asking Buildbucket for the build status
[01:06:51.266] Build 8915703818992523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:51.266] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:55:0) after 1m0s
[01:07:51.301] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:55:0)
[01:07:51.301] Timer tick, asking Buildbucket for the build status
[01:07:51.502] Build 8915703818992523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:51.502] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:56:0) after 1m0s
[01:08:51.526] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:56:0)
[01:08:51.526] Timer tick, asking Buildbucket for the build status
[01:08:51.622] Build 8915703818992523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:51.622] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:57:0) after 1m0s
[01:09:51.648] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:57:0)
[01:09:51.648] Timer tick, asking Buildbucket for the build status
[01:09:51.881] Build 8915703818992523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:51.881] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:58:0) after 1m0s
[01:10:51.917] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:58:0)
[01:10:51.917] Timer tick, asking Buildbucket for the build status
[01:10:52.492] Build 8915703818992523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:52.492] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:59:0) after 1m0s
[01:11:52.519] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:59:0)
[01:11:52.519] Timer tick, asking Buildbucket for the build status
[01:11:52.807] Build 8915703818992523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:52.807] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:60:0) after 1m0s
[01:12:52.891] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:60:0)
[01:12:52.891] Timer tick, asking Buildbucket for the build status
[01:12:53.359] Build 8915703818992523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:53.359] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9081133880968335792:61:0) after 1m0s
[01:12:57.653] Received PubSub notification, asking Buildbucket for the build status
[01:12:57.739] Build 8915703818992523760: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:12:57.739] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1555722776810195",
"created_by": "project:infra",
"created_ts": "1555719277349252",
"id": "8915703818992523760",
"parameters_json": "{\"builder_name\": \"infra-continuous-win10-64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"01ab4adc345fdfd8b7e58e2fffdfb87460edee2f\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"dev-chrome-win10-2-7he2\", \"branch\": \"refs/heads/master\", \"buildername\": \"infra-continuous-win10-64\", \"buildnumber\": 8154, \"got_revision\": \"01ab4adc345fdfd8b7e58e2fffdfb87460edee2f\", \"got_revision_cp\": \"refs/heads/master@{#22518}\", \"path_config\": \"generic\", \"recipe\": \"infra_continuous\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"01ab4adc345fdfd8b7e58e2fffdfb87460edee2f\"}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"dev-chrome-win10-2-7he2\"], \"image\": [\"chrome-win10-1703-2019-03-26-01bb59c9983\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"Chrome\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4267-53b17d5\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"started_ts": "1555722287058233",
"status": "COMPLETED",
"status_changed_ts": "1555722777265119",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/8154",
"builder:infra-continuous-win10-64",
"buildset:commit/git/01ab4adc345fdfd8b7e58e2fffdfb87460edee2f",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/01ab4adc345fdfd8b7e58e2fffdfb87460edee2f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081133880968335792",
"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/8915703818992523760/+/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:4450e9d8e7aa7f10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1555722777269142",
"url": "https://luci-milo-dev.appspot.com/b/8915703818992523760",
"utcnow_ts": "1555722777727146"
}
}
[01:12:57.739] Invocation finished in 58m21.658170863s with status SUCCEEDED