[10:43:42.107] New invocation is queued and will start shortly
[10:43:43.213] Starting the invocation (attempt 1)
[10:43:43.267] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[10:43:43.270] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[10:43:43.270] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9019850752121948208",
"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\":\"548e49c667adcc427587d631e3c5a2cb494d4acb\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@548e49c667adcc427587d631e3c5a2cb494d4acb\",\"title\":\"548e49c667adcc427587d631e3c5a2cb494d4acb\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/548e49c667adcc427587d631e3c5a2cb494d4acb\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"dfb4e8ce1df705ecdf7e16a6b25351648865193e\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@dfb4e8ce1df705ecdf7e16a6b25351648865193e\",\"title\":\"dfb4e8ce1df705ecdf7e16a6b25351648865193e\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/dfb4e8ce1df705ecdf7e16a6b25351648865193e\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"dfb4e8ce1df705ecdf7e16a6b25351648865193e\"}}",
"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:9019850752121948208",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/dfb4e8ce1df705ecdf7e16a6b25351648865193e",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/dfb4e8ce1df705ecdf7e16a6b25351648865193e",
"gitiles_ref:refs/heads/master"
]
}
[10:43:44.101] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1614163423421078",
"id": "8854420690081217360",
"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\": \"548e49c667adcc427587d631e3c5a2cb494d4acb\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@548e49c667adcc427587d631e3c5a2cb494d4acb\", \"title\": \"548e49c667adcc427587d631e3c5a2cb494d4acb\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/548e49c667adcc427587d631e3c5a2cb494d4acb\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"dfb4e8ce1df705ecdf7e16a6b25351648865193e\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@dfb4e8ce1df705ecdf7e16a6b25351648865193e\", \"title\": \"dfb4e8ce1df705ecdf7e16a6b25351648865193e\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/dfb4e8ce1df705ecdf7e16a6b25351648865193e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"dfb4e8ce1df705ecdf7e16a6b25351648865193e\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614163423952718",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18695",
"builder:infra-continuous-win10-64",
"buildset:commit/git/dfb4e8ce1df705ecdf7e16a6b25351648865193e",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/dfb4e8ce1df705ecdf7e16a6b25351648865193e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019850752121948208",
"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/8854420690081217360/+/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": "1614163423952832",
"url": "https://luci-milo-dev.appspot.com/b/8854420690081217360",
"utcnow_ts": "1614163424092722"
}
}
[10:43:44.101] Task URL: https://luci-milo-dev.appspot.com/b/8854420690081217360
[10:43:44.101] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:2:0) after 1m0s
[10:44:44.117] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:2:0)
[10:44:44.117] Timer tick, asking Buildbucket for the build status
[10:44:44.189] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:44:44.189] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:3:0) after 1m0s
[10:45:44.210] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:3:0)
[10:45:44.210] Timer tick, asking Buildbucket for the build status
[10:45:44.288] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:45:44.288] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:4:0) after 1m0s
[10:46:44.326] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:4:0)
[10:46:44.326] Timer tick, asking Buildbucket for the build status
[10:46:44.408] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:46:44.408] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:5:0) after 1m0s
[10:47:44.427] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:5:0)
[10:47:44.427] Timer tick, asking Buildbucket for the build status
[10:47:44.503] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:47:44.503] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:6:0) after 1m0s
[10:48:44.525] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:6:0)
[10:48:44.525] Timer tick, asking Buildbucket for the build status
[10:48:44.595] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:48:44.596] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:7:0) after 1m0s
[10:49:44.615] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:7:0)
[10:49:44.615] Timer tick, asking Buildbucket for the build status
[10:49:44.699] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:49:44.699] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:8:0) after 1m0s
[10:50:44.756] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:8:0)
[10:50:44.756] Timer tick, asking Buildbucket for the build status
[10:50:44.825] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:50:44.825] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:9:0) after 1m0s
[10:51:44.847] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:9:0)
[10:51:44.847] Timer tick, asking Buildbucket for the build status
[10:51:44.927] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:51:44.927] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:10:0) after 1m0s
[10:52:44.957] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:10:0)
[10:52:44.957] Timer tick, asking Buildbucket for the build status
[10:52:45.046] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:52:45.046] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:11:0) after 1m0s
[10:53:45.074] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:11:0)
[10:53:45.074] Timer tick, asking Buildbucket for the build status
[10:53:45.152] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:53:45.152] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:12:0) after 1m0s
[10:54:45.253] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:12:0)
[10:54:45.253] Timer tick, asking Buildbucket for the build status
[10:54:45.335] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:54:45.335] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:13:0) after 1m0s
[10:55:45.412] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:13:0)
[10:55:45.412] Timer tick, asking Buildbucket for the build status
[10:55:45.483] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:55:45.483] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:14:0) after 1m0s
[10:56:45.572] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:14:0)
[10:56:45.572] Timer tick, asking Buildbucket for the build status
[10:56:45.686] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:56:45.686] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:15:0) after 1m0s
[10:57:45.720] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:15:0)
[10:57:45.720] Timer tick, asking Buildbucket for the build status
[10:57:45.784] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:57:45.784] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:16:0) after 1m0s
[10:58:45.865] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:16:0)
[10:58:45.865] Timer tick, asking Buildbucket for the build status
[10:58:45.941] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:58:45.941] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:17:0) after 1m0s
[10:59:45.957] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:17:0)
[10:59:45.957] Timer tick, asking Buildbucket for the build status
[10:59:46.032] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:59:46.032] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:18:0) after 1m0s
[11:00:46.078] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:18:0)
[11:00:46.078] Timer tick, asking Buildbucket for the build status
[11:00:46.156] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:00:46.156] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:19:0) after 1m0s
[11:01:46.256] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:19:0)
[11:01:46.256] Timer tick, asking Buildbucket for the build status
[11:01:46.336] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:01:46.336] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:20:0) after 1m0s
[11:02:46.414] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:20:0)
[11:02:46.414] Timer tick, asking Buildbucket for the build status
[11:02:46.495] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:02:46.495] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:21:0) after 1m0s
[11:03:46.516] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:21:0)
[11:03:46.516] Timer tick, asking Buildbucket for the build status
[11:03:46.584] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:03:46.584] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:22:0) after 1m0s
[11:04:46.603] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:22:0)
[11:04:46.603] Timer tick, asking Buildbucket for the build status
[11:04:46.691] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:04:46.691] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:23:0) after 1m0s
[11:05:46.761] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:23:0)
[11:05:46.761] Timer tick, asking Buildbucket for the build status
[11:05:46.871] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:05:46.871] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:24:0) after 1m0s
[11:06:46.889] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:24:0)
[11:06:46.889] Timer tick, asking Buildbucket for the build status
[11:06:46.976] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:06:46.976] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:25:0) after 1m0s
[11:07:47.003] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:25:0)
[11:07:47.003] Timer tick, asking Buildbucket for the build status
[11:07:47.161] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:07:47.161] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:26:0) after 1m0s
[11:08:47.265] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:26:0)
[11:08:47.265] Timer tick, asking Buildbucket for the build status
[11:08:47.351] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:08:47.351] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:27:0) after 1m0s
[11:09:47.424] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:27:0)
[11:09:47.424] Timer tick, asking Buildbucket for the build status
[11:09:47.506] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:09:47.506] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:28:0) after 1m0s
[11:10:47.632] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:28:0)
[11:10:47.632] Timer tick, asking Buildbucket for the build status
[11:10:47.704] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:10:47.704] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:29:0) after 1m0s
[11:11:47.824] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:29:0)
[11:11:47.824] Timer tick, asking Buildbucket for the build status
[11:11:47.933] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:11:47.933] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:30:0) after 1m0s
[11:12:48.025] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:30:0)
[11:12:48.025] Timer tick, asking Buildbucket for the build status
[11:12:48.102] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:12:48.102] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:31:0) after 1m0s
[11:13:48.156] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:31:0)
[11:13:48.156] Timer tick, asking Buildbucket for the build status
[11:13:48.271] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:13:48.271] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:32:0) after 1m0s
[11:14:48.355] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:32:0)
[11:14:48.355] Timer tick, asking Buildbucket for the build status
[11:14:48.442] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:14:48.442] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:33:0) after 1m0s
[11:15:48.481] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:33:0)
[11:15:48.481] Timer tick, asking Buildbucket for the build status
[11:15:48.561] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:15:48.561] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:34:0) after 1m0s
[11:16:48.588] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:34:0)
[11:16:48.588] Timer tick, asking Buildbucket for the build status
[11:16:48.676] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:16:48.676] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:35:0) after 1m0s
[11:17:48.761] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:35:0)
[11:17:48.761] Timer tick, asking Buildbucket for the build status
[11:17:48.838] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:17:48.838] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:36:0) after 1m0s
[11:18:48.867] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:36:0)
[11:18:48.867] Timer tick, asking Buildbucket for the build status
[11:18:48.949] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:18:48.949] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:37:0) after 1m0s
[11:19:49.014] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:37:0)
[11:19:49.014] Timer tick, asking Buildbucket for the build status
[11:19:49.091] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:19:49.091] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:38:0) after 1m0s
[11:20:49.112] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:38:0)
[11:20:49.112] Timer tick, asking Buildbucket for the build status
[11:20:49.189] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:20:49.189] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:39:0) after 1m0s
[11:21:49.286] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:39:0)
[11:21:49.286] Timer tick, asking Buildbucket for the build status
[11:21:49.359] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:21:49.359] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:40:0) after 1m0s
[11:22:49.462] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:40:0)
[11:22:49.462] Timer tick, asking Buildbucket for the build status
[11:22:49.543] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:22:49.543] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:41:0) after 1m0s
[11:23:49.567] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:41:0)
[11:23:49.567] Timer tick, asking Buildbucket for the build status
[11:23:49.689] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:23:49.689] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:42:0) after 1m0s
[11:24:49.710] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:42:0)
[11:24:49.710] Timer tick, asking Buildbucket for the build status
[11:24:49.790] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:24:49.790] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:43:0) after 1m0s
[11:25:49.811] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:43:0)
[11:25:49.811] Timer tick, asking Buildbucket for the build status
[11:25:49.898] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:25:49.898] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:44:0) after 1m0s
[11:26:49.956] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:44:0)
[11:26:49.956] Timer tick, asking Buildbucket for the build status
[11:26:50.027] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:26:50.027] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:45:0) after 1m0s
[11:27:50.114] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:45:0)
[11:27:50.114] Timer tick, asking Buildbucket for the build status
[11:27:50.199] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:27:50.199] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:46:0) after 1m0s
[11:28:50.260] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:46:0)
[11:28:50.260] Timer tick, asking Buildbucket for the build status
[11:28:50.330] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:28:50.330] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:47:0) after 1m0s
[11:29:50.371] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:47:0)
[11:29:50.371] Timer tick, asking Buildbucket for the build status
[11:29:50.444] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:29:50.444] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:48:0) after 1m0s
[11:30:50.483] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:48:0)
[11:30:50.483] Timer tick, asking Buildbucket for the build status
[11:30:50.646] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:30:50.646] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:49:0) after 1m0s
[11:31:50.674] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:49:0)
[11:31:50.674] Timer tick, asking Buildbucket for the build status
[11:31:50.751] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:31:50.752] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:50:0) after 1m0s
[11:32:50.831] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:50:0)
[11:32:50.831] Timer tick, asking Buildbucket for the build status
[11:32:50.926] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:32:50.926] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:51:0) after 1m0s
[11:33:50.956] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:51:0)
[11:33:50.956] Timer tick, asking Buildbucket for the build status
[11:33:51.039] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:33:51.039] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:52:0) after 1m0s
[11:34:51.069] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:52:0)
[11:34:51.069] Timer tick, asking Buildbucket for the build status
[11:34:51.178] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:34:51.178] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:53:0) after 1m0s
[11:35:51.257] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:53:0)
[11:35:51.257] Timer tick, asking Buildbucket for the build status
[11:35:51.328] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:35:51.328] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:54:0) after 1m0s
[11:36:51.366] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:54:0)
[11:36:51.366] Timer tick, asking Buildbucket for the build status
[11:36:51.442] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:36:51.442] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:55:0) after 1m0s
[11:37:51.477] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:55:0)
[11:37:51.477] Timer tick, asking Buildbucket for the build status
[11:37:51.571] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:37:51.571] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:56:0) after 1m0s
[11:38:51.621] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:56:0)
[11:38:51.621] Timer tick, asking Buildbucket for the build status
[11:38:51.785] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:38:51.785] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:57:0) after 1m0s
[11:39:51.809] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:57:0)
[11:39:51.809] Timer tick, asking Buildbucket for the build status
[11:39:51.885] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:39:51.885] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:58:0) after 1m0s
[11:40:51.957] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:58:0)
[11:40:51.957] Timer tick, asking Buildbucket for the build status
[11:40:52.045] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:40:52.045] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:59:0) after 1m0s
[11:41:52.087] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:59:0)
[11:41:52.087] Timer tick, asking Buildbucket for the build status
[11:41:52.176] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:41:52.176] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:60:0) after 1m0s
[11:42:52.224] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:60:0)
[11:42:52.224] Timer tick, asking Buildbucket for the build status
[11:42:52.302] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:42:52.302] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:61:0) after 1m0s
[11:43:52.348] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:61:0)
[11:43:52.348] Timer tick, asking Buildbucket for the build status
[11:43:52.427] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:43:52.427] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:62:0) after 1m0s
[11:44:52.474] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:62:0)
[11:44:52.474] Timer tick, asking Buildbucket for the build status
[11:44:52.552] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:44:52.552] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:63:0) after 1m0s
[11:45:52.580] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:63:0)
[11:45:52.580] Timer tick, asking Buildbucket for the build status
[11:45:52.729] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:45:52.729] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:64:0) after 1m0s
[11:46:52.761] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:64:0)
[11:46:52.761] Timer tick, asking Buildbucket for the build status
[11:46:53.092] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:46:53.092] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:65:0) after 1m0s
[11:47:53.164] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:65:0)
[11:47:53.164] Timer tick, asking Buildbucket for the build status
[11:47:53.329] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:47:53.329] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:66:0) after 1m0s
[11:48:53.363] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:66:0)
[11:48:53.363] Timer tick, asking Buildbucket for the build status
[11:48:53.456] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:48:53.456] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:67:0) after 1m0s
[11:49:53.492] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:67:0)
[11:49:53.492] Timer tick, asking Buildbucket for the build status
[11:49:53.572] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:49:53.572] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:68:0) after 1m0s
[11:50:53.657] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:68:0)
[11:50:53.657] Timer tick, asking Buildbucket for the build status
[11:50:53.757] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:50:53.757] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:69:0) after 1m0s
[11:51:53.778] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:69:0)
[11:51:53.778] Timer tick, asking Buildbucket for the build status
[11:51:53.883] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:51:53.883] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:70:0) after 1m0s
[11:52:53.969] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:70:0)
[11:52:53.969] Timer tick, asking Buildbucket for the build status
[11:52:54.066] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:52:54.066] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:71:0) after 1m0s
[11:53:54.171] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:71:0)
[11:53:54.171] Timer tick, asking Buildbucket for the build status
[11:53:54.260] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:53:54.260] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:72:0) after 1m0s
[11:54:54.380] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:72:0)
[11:54:54.380] Timer tick, asking Buildbucket for the build status
[11:54:54.451] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:54:54.451] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:73:0) after 1m0s
[11:55:54.485] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:73:0)
[11:55:54.485] Timer tick, asking Buildbucket for the build status
[11:55:54.583] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:55:54.583] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:74:0) after 1m0s
[11:56:54.613] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:74:0)
[11:56:54.613] Timer tick, asking Buildbucket for the build status
[11:56:54.766] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:56:54.766] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:75:0) after 1m0s
[11:57:54.816] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:75:0)
[11:57:54.816] Timer tick, asking Buildbucket for the build status
[11:57:54.899] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:57:54.899] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:76:0) after 1m0s
[11:58:54.970] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:76:0)
[11:58:54.970] Timer tick, asking Buildbucket for the build status
[11:58:55.045] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:58:55.045] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:77:0) after 1m0s
[11:59:55.112] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:77:0)
[11:59:55.112] Timer tick, asking Buildbucket for the build status
[11:59:55.211] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:59:55.211] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:78:0) after 1m0s
[12:00:55.313] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:78:0)
[12:00:55.313] Timer tick, asking Buildbucket for the build status
[12:00:55.414] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:00:55.414] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:79:0) after 1m0s
[12:01:55.515] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:79:0)
[12:01:55.515] Timer tick, asking Buildbucket for the build status
[12:01:55.624] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:01:55.624] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:80:0) after 1m0s
[12:02:55.721] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:80:0)
[12:02:55.721] Timer tick, asking Buildbucket for the build status
[12:02:55.873] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:02:55.873] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:81:0) after 1m0s
[12:03:55.958] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:81:0)
[12:03:55.958] Timer tick, asking Buildbucket for the build status
[12:03:56.045] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:03:56.045] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:82:0) after 1m0s
[12:04:56.127] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:82:0)
[12:04:56.127] Timer tick, asking Buildbucket for the build status
[12:04:56.282] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:04:56.282] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:83:0) after 1m0s
[12:05:56.309] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:83:0)
[12:05:56.309] Timer tick, asking Buildbucket for the build status
[12:05:56.391] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:05:56.391] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:84:0) after 1m0s
[12:06:56.470] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:84:0)
[12:06:56.470] Timer tick, asking Buildbucket for the build status
[12:06:56.549] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:06:56.550] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:85:0) after 1m0s
[12:07:56.622] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:85:0)
[12:07:56.622] Timer tick, asking Buildbucket for the build status
[12:07:56.717] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:07:56.717] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:86:0) after 1m0s
[12:08:56.778] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:86:0)
[12:08:56.778] Timer tick, asking Buildbucket for the build status
[12:08:56.864] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:08:56.864] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:87:0) after 1m0s
[12:09:56.991] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:87:0)
[12:09:56.992] Timer tick, asking Buildbucket for the build status
[12:09:57.113] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:09:57.113] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:88:0) after 1m0s
[12:10:57.147] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:88:0)
[12:10:57.147] Timer tick, asking Buildbucket for the build status
[12:10:57.232] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:10:57.232] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:89:0) after 1m0s
[12:11:57.331] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:89:0)
[12:11:57.331] Timer tick, asking Buildbucket for the build status
[12:11:57.417] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:11:57.417] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:90:0) after 1m0s
[12:12:57.441] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:90:0)
[12:12:57.441] Timer tick, asking Buildbucket for the build status
[12:12:57.535] Build 8854420690081217360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:12:57.535] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:91:0) after 1m0s
[12:13:56.656] Received PubSub notification, asking Buildbucket for the build status
[12:13:56.761] Build 8854420690081217360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:57.616] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:91:0)
[12:13:57.616] Timer tick, asking Buildbucket for the build status
[12:13:57.745] Build 8854420690081217360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:57.745] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:93:0) after 1m0s
[12:14:57.816] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:93:0)
[12:14:57.816] Timer tick, asking Buildbucket for the build status
[12:14:57.911] Build 8854420690081217360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:57.911] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:94:0) after 1m0s
[12:15:58.021] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:94:0)
[12:15:58.021] Timer tick, asking Buildbucket for the build status
[12:15:58.167] Build 8854420690081217360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:58.167] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:95:0) after 1m0s
[12:16:58.275] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:95:0)
[12:16:58.275] Timer tick, asking Buildbucket for the build status
[12:16:58.360] Build 8854420690081217360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:58.360] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:96:0) after 1m0s
[12:17:58.429] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:96:0)
[12:17:58.429] Timer tick, asking Buildbucket for the build status
[12:17:58.522] Build 8854420690081217360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:58.522] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:97:0) after 1m0s
[12:18:58.627] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:97:0)
[12:18:58.627] Timer tick, asking Buildbucket for the build status
[12:18:58.709] Build 8854420690081217360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:58.709] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:98:0) after 1m0s
[12:19:58.765] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:98:0)
[12:19:58.765] Timer tick, asking Buildbucket for the build status
[12:19:58.866] Build 8854420690081217360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:58.866] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019850752121948208:99:0) after 1m0s
[12:20:16.630] Received PubSub notification, asking Buildbucket for the build status
[12:20:16.715] Build 8854420690081217360: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:20:16.716] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1614169215349712",
"created_by": "project:infra",
"created_ts": "1614163423421078",
"id": "8854420690081217360",
"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\": \"548e49c667adcc427587d631e3c5a2cb494d4acb\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@548e49c667adcc427587d631e3c5a2cb494d4acb\", \"title\": \"548e49c667adcc427587d631e3c5a2cb494d4acb\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/548e49c667adcc427587d631e3c5a2cb494d4acb\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"dfb4e8ce1df705ecdf7e16a6b25351648865193e\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@dfb4e8ce1df705ecdf7e16a6b25351648865193e\", \"title\": \"dfb4e8ce1df705ecdf7e16a6b25351648865193e\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/dfb4e8ce1df705ecdf7e16a6b25351648865193e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"dfb4e8ce1df705ecdf7e16a6b25351648865193e\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"dfb4e8ce1df705ecdf7e16a6b25351648865193e\", \"got_revision_cp\": \"refs/heads/master@{#38780}\"}, \"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-1h6g\"], \"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": "1614168832618178",
"status": "COMPLETED",
"status_changed_ts": "1614168832982190",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18695",
"builder:infra-continuous-win10-64",
"buildset:commit/git/dfb4e8ce1df705ecdf7e16a6b25351648865193e",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/dfb4e8ce1df705ecdf7e16a6b25351648865193e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019850752121948208",
"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/8854420690081217360/+/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:51ec74bc22a48d10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1614169215350380",
"url": "https://luci-milo-dev.appspot.com/b/8854420690081217360",
"utcnow_ts": "1614169216706948"
}
}
[12:20:16.716] Invocation finished in 1h36m34.624054695s with status SUCCEEDED