[21:14:12.271] New invocation is queued and will start shortly
[21:14:13.412] Starting the invocation (attempt 1)
[21:14:13.453] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[21:14:13.453] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[21:14:13.453] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9032766450515813488",
"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\":\"4fef6fb9e50cba78822598a9f48a568c8c85337b\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4fef6fb9e50cba78822598a9f48a568c8c85337b\",\"title\":\"4fef6fb9e50cba78822598a9f48a568c8c85337b\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/4fef6fb9e50cba78822598a9f48a568c8c85337b\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"4fef6fb9e50cba78822598a9f48a568c8c85337b\"}}",
"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:9032766450515813488",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/4fef6fb9e50cba78822598a9f48a568c8c85337b",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/4fef6fb9e50cba78822598a9f48a568c8c85337b",
"gitiles_ref:refs/heads/master"
]
}
[21:14:14.242] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1601846053545078",
"id": "8867336388516650736",
"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\": \"4fef6fb9e50cba78822598a9f48a568c8c85337b\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4fef6fb9e50cba78822598a9f48a568c8c85337b\", \"title\": \"4fef6fb9e50cba78822598a9f48a568c8c85337b\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/4fef6fb9e50cba78822598a9f48a568c8c85337b\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"4fef6fb9e50cba78822598a9f48a568c8c85337b\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1601846054080587",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17809",
"builder:infra-continuous-win10-64",
"buildset:commit/git/4fef6fb9e50cba78822598a9f48a568c8c85337b",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/4fef6fb9e50cba78822598a9f48a568c8c85337b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032766450515813488",
"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/8867336388516650736/+/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": "1601846054081100",
"url": "https://luci-milo-dev.appspot.com/b/8867336388516650736",
"utcnow_ts": "1601846054232175"
}
}
[21:14:14.242] Task URL: https://luci-milo-dev.appspot.com/b/8867336388516650736
[21:14:14.242] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:2:0) after 1m0s
[21:15:14.264] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:2:0)
[21:15:14.264] Timer tick, asking Buildbucket for the build status
[21:15:14.358] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:15:14.358] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:3:0) after 1m0s
[21:16:14.378] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:3:0)
[21:16:14.378] Timer tick, asking Buildbucket for the build status
[21:16:14.482] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:16:14.482] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:4:0) after 1m0s
[21:17:14.502] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:4:0)
[21:17:14.502] Timer tick, asking Buildbucket for the build status
[21:17:14.591] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:17:14.591] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:5:0) after 1m0s
[21:18:14.617] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:5:0)
[21:18:14.617] Timer tick, asking Buildbucket for the build status
[21:18:14.707] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:18:14.707] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:6:0) after 1m0s
[21:19:14.728] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:6:0)
[21:19:14.728] Timer tick, asking Buildbucket for the build status
[21:19:14.834] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:19:14.834] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:7:0) after 1m0s
[21:20:14.890] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:7:0)
[21:20:14.890] Timer tick, asking Buildbucket for the build status
[21:20:14.976] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:20:14.976] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:8:0) after 1m0s
[21:21:15.079] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:8:0)
[21:21:15.079] Timer tick, asking Buildbucket for the build status
[21:21:15.174] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:21:15.174] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:9:0) after 1m0s
[21:22:15.193] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:9:0)
[21:22:15.193] Timer tick, asking Buildbucket for the build status
[21:22:15.303] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:22:15.303] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:10:0) after 1m0s
[21:23:15.368] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:10:0)
[21:23:15.368] Timer tick, asking Buildbucket for the build status
[21:23:15.478] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:23:15.478] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:11:0) after 1m0s
[21:24:15.495] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:11:0)
[21:24:15.495] Timer tick, asking Buildbucket for the build status
[21:24:15.609] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:24:15.609] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:12:0) after 1m0s
[21:25:15.633] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:12:0)
[21:25:15.633] Timer tick, asking Buildbucket for the build status
[21:25:15.747] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:25:15.747] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:13:0) after 1m0s
[21:26:15.766] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:13:0)
[21:26:15.766] Timer tick, asking Buildbucket for the build status
[21:26:15.895] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:26:15.895] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:14:0) after 1m0s
[21:27:15.914] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:14:0)
[21:27:15.914] Timer tick, asking Buildbucket for the build status
[21:27:15.993] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:27:15.993] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:15:0) after 1m0s
[21:28:16.074] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:15:0)
[21:28:16.074] Timer tick, asking Buildbucket for the build status
[21:28:16.173] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:28:16.173] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:16:0) after 1m0s
[21:29:16.273] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:16:0)
[21:29:16.273] Timer tick, asking Buildbucket for the build status
[21:29:16.364] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:16.364] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:17:0) after 1m0s
[21:30:16.435] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:17:0)
[21:30:16.435] Timer tick, asking Buildbucket for the build status
[21:30:16.536] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:16.536] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:18:0) after 1m0s
[21:31:16.558] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:18:0)
[21:31:16.558] Timer tick, asking Buildbucket for the build status
[21:31:16.687] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:16.687] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:19:0) after 1m0s
[21:32:16.706] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:19:0)
[21:32:16.706] Timer tick, asking Buildbucket for the build status
[21:32:16.792] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:16.792] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:20:0) after 1m0s
[21:33:16.812] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:20:0)
[21:33:16.812] Timer tick, asking Buildbucket for the build status
[21:33:16.889] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:16.889] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:21:0) after 1m0s
[21:34:16.974] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:21:0)
[21:34:16.974] Timer tick, asking Buildbucket for the build status
[21:34:17.106] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:17.106] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:22:0) after 1m0s
[21:35:17.174] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:22:0)
[21:35:17.174] Timer tick, asking Buildbucket for the build status
[21:35:17.337] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:35:17.337] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:23:0) after 1m0s
[21:36:17.368] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:23:0)
[21:36:17.368] Timer tick, asking Buildbucket for the build status
[21:36:17.458] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:17.458] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:24:0) after 1m0s
[21:37:17.488] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:24:0)
[21:37:17.488] Timer tick, asking Buildbucket for the build status
[21:37:17.576] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:17.576] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:25:0) after 1m0s
[21:38:17.602] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:25:0)
[21:38:17.602] Timer tick, asking Buildbucket for the build status
[21:38:17.690] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:17.690] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:26:0) after 1m0s
[21:39:17.715] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:26:0)
[21:39:17.715] Timer tick, asking Buildbucket for the build status
[21:39:17.798] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:17.798] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:27:0) after 1m0s
[21:40:17.874] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:27:0)
[21:40:17.874] Timer tick, asking Buildbucket for the build status
[21:40:18.017] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:18.017] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:28:0) after 1m0s
[21:41:18.065] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:28:0)
[21:41:18.065] Timer tick, asking Buildbucket for the build status
[21:41:18.212] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:18.212] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:29:0) after 1m0s
[21:42:18.263] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:29:0)
[21:42:18.263] Timer tick, asking Buildbucket for the build status
[21:42:18.331] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:18.331] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:30:0) after 1m0s
[21:43:18.355] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:30:0)
[21:43:18.355] Timer tick, asking Buildbucket for the build status
[21:43:18.433] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:18.433] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:31:0) after 1m0s
[21:44:18.455] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:31:0)
[21:44:18.455] Timer tick, asking Buildbucket for the build status
[21:44:18.545] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:18.545] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:32:0) after 1m0s
[21:45:18.569] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:32:0)
[21:45:18.569] Timer tick, asking Buildbucket for the build status
[21:45:18.681] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:18.681] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:33:0) after 1m0s
[21:46:18.700] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:33:0)
[21:46:18.700] Timer tick, asking Buildbucket for the build status
[21:46:18.783] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:46:18.783] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:34:0) after 1m0s
[21:47:18.804] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:34:0)
[21:47:18.804] Timer tick, asking Buildbucket for the build status
[21:47:18.892] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:18.892] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:35:0) after 1m0s
[21:48:18.912] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:35:0)
[21:48:18.912] Timer tick, asking Buildbucket for the build status
[21:48:19.035] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:19.035] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:36:0) after 1m0s
[21:49:19.055] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:36:0)
[21:49:19.055] Timer tick, asking Buildbucket for the build status
[21:49:19.152] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:19.152] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:37:0) after 1m0s
[21:50:19.186] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:37:0)
[21:50:19.186] Timer tick, asking Buildbucket for the build status
[21:50:19.336] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:19.336] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:38:0) after 1m0s
[21:51:19.355] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:38:0)
[21:51:19.355] Timer tick, asking Buildbucket for the build status
[21:51:19.440] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:19.440] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:39:0) after 1m0s
[21:52:19.467] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:39:0)
[21:52:19.467] Timer tick, asking Buildbucket for the build status
[21:52:19.556] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:19.556] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:40:0) after 1m0s
[21:53:19.582] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:40:0)
[21:53:19.582] Timer tick, asking Buildbucket for the build status
[21:53:19.656] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:19.656] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:41:0) after 1m0s
[21:54:19.680] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:41:0)
[21:54:19.680] Timer tick, asking Buildbucket for the build status
[21:54:19.752] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:19.752] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:42:0) after 1m0s
[21:55:19.776] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:42:0)
[21:55:19.777] Timer tick, asking Buildbucket for the build status
[21:55:19.873] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:19.873] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:43:0) after 1m0s
[21:56:19.893] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:43:0)
[21:56:19.893] Timer tick, asking Buildbucket for the build status
[21:56:19.985] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:19.985] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:44:0) after 1m0s
[21:57:20.016] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:44:0)
[21:57:20.016] Timer tick, asking Buildbucket for the build status
[21:57:20.125] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:20.125] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:45:0) after 1m0s
[21:58:20.206] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:45:0)
[21:58:20.206] Timer tick, asking Buildbucket for the build status
[21:58:20.550] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:20.550] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:46:0) after 1m0s
[21:59:20.574] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:46:0)
[21:59:20.574] Timer tick, asking Buildbucket for the build status
[21:59:20.703] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:20.703] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:47:0) after 1m0s
[22:00:20.725] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:47:0)
[22:00:20.725] Timer tick, asking Buildbucket for the build status
[22:00:21.362] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:21.362] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:48:0) after 1m0s
[22:01:21.395] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:48:0)
[22:01:21.395] Timer tick, asking Buildbucket for the build status
[22:01:21.491] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:21.491] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:49:0) after 1m0s
[22:02:21.515] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:49:0)
[22:02:21.515] Timer tick, asking Buildbucket for the build status
[22:02:21.770] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:21.770] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:50:0) after 1m0s
[22:03:21.866] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:50:0)
[22:03:21.866] Timer tick, asking Buildbucket for the build status
[22:03:21.989] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:21.989] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:51:0) after 1m0s
[22:04:22.099] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:51:0)
[22:04:22.099] Timer tick, asking Buildbucket for the build status
[22:04:22.463] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:22.463] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:52:0) after 1m0s
[22:05:22.486] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:52:0)
[22:05:22.486] Timer tick, asking Buildbucket for the build status
[22:05:22.685] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:22.685] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:53:0) after 1m0s
[22:06:22.709] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:53:0)
[22:06:22.709] Timer tick, asking Buildbucket for the build status
[22:06:22.802] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:22.802] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:54:0) after 1m0s
[22:07:22.823] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:54:0)
[22:07:22.823] Timer tick, asking Buildbucket for the build status
[22:07:22.918] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:22.918] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:55:0) after 1m0s
[22:08:22.942] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:55:0)
[22:08:22.942] Timer tick, asking Buildbucket for the build status
[22:08:23.035] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:23.035] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:56:0) after 1m0s
[22:09:23.065] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:56:0)
[22:09:23.065] Timer tick, asking Buildbucket for the build status
[22:09:23.153] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:23.153] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:57:0) after 1m0s
[22:10:23.181] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:57:0)
[22:10:23.181] Timer tick, asking Buildbucket for the build status
[22:10:23.272] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:23.272] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:58:0) after 1m0s
[22:11:23.296] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:58:0)
[22:11:23.296] Timer tick, asking Buildbucket for the build status
[22:11:23.384] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:23.384] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:59:0) after 1m0s
[22:12:23.407] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:59:0)
[22:12:23.407] Timer tick, asking Buildbucket for the build status
[22:12:23.498] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:23.498] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:60:0) after 1m0s
[22:13:23.519] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:60:0)
[22:13:23.519] Timer tick, asking Buildbucket for the build status
[22:13:23.608] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:23.608] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:61:0) after 1m0s
[22:14:23.629] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:61:0)
[22:14:23.629] Timer tick, asking Buildbucket for the build status
[22:14:23.741] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:23.741] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:62:0) after 1m0s
[22:15:23.763] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:62:0)
[22:15:23.763] Timer tick, asking Buildbucket for the build status
[22:15:23.880] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:23.880] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:63:0) after 1m0s
[22:16:23.904] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:63:0)
[22:16:23.904] Timer tick, asking Buildbucket for the build status
[22:16:23.990] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:23.990] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:64:0) after 1m0s
[22:17:24.011] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:64:0)
[22:17:24.011] Timer tick, asking Buildbucket for the build status
[22:17:24.135] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:24.135] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:65:0) after 1m0s
[22:18:24.176] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:65:0)
[22:18:24.176] Timer tick, asking Buildbucket for the build status
[22:18:24.307] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:24.307] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:66:0) after 1m0s
[22:19:24.364] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:66:0)
[22:19:24.364] Timer tick, asking Buildbucket for the build status
[22:19:24.523] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:24.523] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:67:0) after 1m0s
[22:20:24.543] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:67:0)
[22:20:24.543] Timer tick, asking Buildbucket for the build status
[22:20:24.636] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:24.636] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:68:0) after 1m0s
[22:21:24.658] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:68:0)
[22:21:24.658] Timer tick, asking Buildbucket for the build status
[22:21:24.734] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:24.734] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:69:0) after 1m0s
[22:22:24.776] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:69:0)
[22:22:24.776] Timer tick, asking Buildbucket for the build status
[22:22:24.867] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:24.867] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:70:0) after 1m0s
[22:23:24.894] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:70:0)
[22:23:24.894] Timer tick, asking Buildbucket for the build status
[22:23:25.009] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:25.009] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:71:0) after 1m0s
[22:24:25.094] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:71:0)
[22:24:25.094] Timer tick, asking Buildbucket for the build status
[22:24:25.188] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:25.188] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:72:0) after 1m0s
[22:25:25.281] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:72:0)
[22:25:25.281] Timer tick, asking Buildbucket for the build status
[22:25:25.410] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:25.410] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:73:0) after 1m0s
[22:26:25.431] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:73:0)
[22:26:25.431] Timer tick, asking Buildbucket for the build status
[22:26:25.507] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:25.507] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:74:0) after 1m0s
[22:27:25.531] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:74:0)
[22:27:25.531] Timer tick, asking Buildbucket for the build status
[22:27:25.631] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:25.631] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:75:0) after 1m0s
[22:28:25.661] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:75:0)
[22:28:25.661] Timer tick, asking Buildbucket for the build status
[22:28:25.741] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:25.741] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:76:0) after 1m0s
[22:29:25.773] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:76:0)
[22:29:25.773] Timer tick, asking Buildbucket for the build status
[22:29:25.891] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:25.891] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:77:0) after 1m0s
[22:30:25.975] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:77:0)
[22:30:25.975] Timer tick, asking Buildbucket for the build status
[22:30:26.065] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:26.065] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:78:0) after 1m0s
[22:31:26.092] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:78:0)
[22:31:26.092] Timer tick, asking Buildbucket for the build status
[22:31:26.225] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:26.225] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:79:0) after 1m0s
[22:32:26.261] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:79:0)
[22:32:26.261] Timer tick, asking Buildbucket for the build status
[22:32:26.337] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:26.337] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:80:0) after 1m0s
[22:33:26.360] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:80:0)
[22:33:26.360] Timer tick, asking Buildbucket for the build status
[22:33:26.449] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:26.449] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:81:0) after 1m0s
[22:34:26.471] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:81:0)
[22:34:26.471] Timer tick, asking Buildbucket for the build status
[22:34:26.551] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:26.551] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:82:0) after 1m0s
[22:35:26.578] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:82:0)
[22:35:26.578] Timer tick, asking Buildbucket for the build status
[22:35:26.742] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:26.742] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:83:0) after 1m0s
[22:36:26.766] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:83:0)
[22:36:26.766] Timer tick, asking Buildbucket for the build status
[22:36:26.863] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:26.863] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:84:0) after 1m0s
[22:37:26.962] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:84:0)
[22:37:26.962] Timer tick, asking Buildbucket for the build status
[22:37:27.047] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:27.047] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:85:0) after 1m0s
[22:38:27.072] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:85:0)
[22:38:27.072] Timer tick, asking Buildbucket for the build status
[22:38:27.161] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:27.161] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:86:0) after 1m0s
[22:39:27.268] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:86:0)
[22:39:27.268] Timer tick, asking Buildbucket for the build status
[22:39:27.345] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:27.345] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:87:0) after 1m0s
[22:40:27.370] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:87:0)
[22:40:27.370] Timer tick, asking Buildbucket for the build status
[22:40:27.451] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:27.451] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:88:0) after 1m0s
[22:41:27.482] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:88:0)
[22:41:27.482] Timer tick, asking Buildbucket for the build status
[22:41:27.557] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:27.557] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:89:0) after 1m0s
[22:42:27.663] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:89:0)
[22:42:27.663] Timer tick, asking Buildbucket for the build status
[22:42:27.813] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:27.813] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:90:0) after 1m0s
[22:43:27.836] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:90:0)
[22:43:27.836] Timer tick, asking Buildbucket for the build status
[22:43:27.934] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:27.934] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:91:0) after 1m0s
[22:44:27.992] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:91:0)
[22:44:27.992] Timer tick, asking Buildbucket for the build status
[22:44:28.121] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:28.121] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:92:0) after 1m0s
[22:45:28.151] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:92:0)
[22:45:28.151] Timer tick, asking Buildbucket for the build status
[22:45:28.282] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:28.282] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:93:0) after 1m0s
[22:46:28.317] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:93:0)
[22:46:28.317] Timer tick, asking Buildbucket for the build status
[22:46:28.397] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:28.397] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:94:0) after 1m0s
[22:47:28.422] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:94:0)
[22:47:28.422] Timer tick, asking Buildbucket for the build status
[22:47:28.506] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:28.506] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:95:0) after 1m0s
[22:48:28.531] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:95:0)
[22:48:28.531] Timer tick, asking Buildbucket for the build status
[22:48:28.613] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:28.613] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:96:0) after 1m0s
[22:49:28.637] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:96:0)
[22:49:28.637] Timer tick, asking Buildbucket for the build status
[22:49:28.728] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:28.728] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:97:0) after 1m0s
[22:50:28.753] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:97:0)
[22:50:28.753] Timer tick, asking Buildbucket for the build status
[22:50:28.971] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:28.971] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:98:0) after 1m0s
[22:51:29.002] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:98:0)
[22:51:29.002] Timer tick, asking Buildbucket for the build status
[22:51:29.087] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:29.087] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:99:0) after 1m0s
[22:52:29.113] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:99:0)
[22:52:29.113] Timer tick, asking Buildbucket for the build status
[22:52:29.211] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:29.211] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:100:0) after 1m0s
[22:53:29.242] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:100:0)
[22:53:29.242] Timer tick, asking Buildbucket for the build status
[22:53:29.337] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:29.337] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:101:0) after 1m0s
[22:54:29.364] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:101:0)
[22:54:29.364] Timer tick, asking Buildbucket for the build status
[22:54:29.523] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:29.523] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:102:0) after 1m0s
[22:55:29.547] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:102:0)
[22:55:29.547] Timer tick, asking Buildbucket for the build status
[22:55:29.632] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:29.633] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:103:0) after 1m0s
[22:56:29.656] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:103:0)
[22:56:29.656] Timer tick, asking Buildbucket for the build status
[22:56:29.737] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:29.737] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:104:0) after 1m0s
[22:57:29.763] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:104:0)
[22:57:29.763] Timer tick, asking Buildbucket for the build status
[22:57:29.852] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:29.852] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:105:0) after 1m0s
[22:58:29.876] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:105:0)
[22:58:29.876] Timer tick, asking Buildbucket for the build status
[22:58:30.011] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:30.011] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:106:0) after 1m0s
[22:59:30.083] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:106:0)
[22:59:30.083] Timer tick, asking Buildbucket for the build status
[22:59:30.166] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:30.166] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:107:0) after 1m0s
[23:00:30.201] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:107:0)
[23:00:30.201] Timer tick, asking Buildbucket for the build status
[23:00:30.399] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:30.400] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:108:0) after 1m0s
[23:01:30.425] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:108:0)
[23:01:30.425] Timer tick, asking Buildbucket for the build status
[23:01:30.526] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:30.526] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:109:0) after 1m0s
[23:02:30.561] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:109:0)
[23:02:30.561] Timer tick, asking Buildbucket for the build status
[23:02:30.709] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:30.709] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:110:0) after 1m0s
[23:03:30.790] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:110:0)
[23:03:30.790] Timer tick, asking Buildbucket for the build status
[23:03:30.887] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:30.887] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:111:0) after 1m0s
[23:04:30.962] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:111:0)
[23:04:30.962] Timer tick, asking Buildbucket for the build status
[23:04:31.122] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:31.122] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:112:0) after 1m0s
[23:05:31.177] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:112:0)
[23:05:31.177] Timer tick, asking Buildbucket for the build status
[23:05:31.250] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:31.250] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:113:0) after 1m0s
[23:06:31.274] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:113:0)
[23:06:31.274] Timer tick, asking Buildbucket for the build status
[23:06:31.372] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:31.372] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:114:0) after 1m0s
[23:07:31.398] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:114:0)
[23:07:31.398] Timer tick, asking Buildbucket for the build status
[23:07:31.494] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:31.494] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:115:0) after 1m0s
[23:08:31.519] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:115:0)
[23:08:31.519] Timer tick, asking Buildbucket for the build status
[23:08:31.597] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:31.597] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:116:0) after 1m0s
[23:09:31.623] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:116:0)
[23:09:31.623] Timer tick, asking Buildbucket for the build status
[23:09:31.707] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:31.707] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:117:0) after 1m0s
[23:10:31.737] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:117:0)
[23:10:31.737] Timer tick, asking Buildbucket for the build status
[23:10:31.821] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:31.821] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:118:0) after 1m0s
[23:11:31.852] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:118:0)
[23:11:31.852] Timer tick, asking Buildbucket for the build status
[23:11:32.020] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:32.020] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:119:0) after 1m0s
[23:12:32.105] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:119:0)
[23:12:32.105] Timer tick, asking Buildbucket for the build status
[23:12:32.192] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:32.192] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:120:0) after 1m0s
[23:13:32.232] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:120:0)
[23:13:32.232] Timer tick, asking Buildbucket for the build status
[23:13:32.327] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:32.327] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:121:0) after 1m0s
[23:14:32.358] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:121:0)
[23:14:32.358] Timer tick, asking Buildbucket for the build status
[23:14:32.443] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:32.443] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:122:0) after 1m0s
[23:15:32.476] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:122:0)
[23:15:32.476] Timer tick, asking Buildbucket for the build status
[23:15:32.573] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:32.573] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:123:0) after 1m0s
[23:16:32.649] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:123:0)
[23:16:32.649] Timer tick, asking Buildbucket for the build status
[23:16:32.733] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:32.733] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:124:0) after 1m0s
[23:17:32.765] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:124:0)
[23:17:32.765] Timer tick, asking Buildbucket for the build status
[23:17:32.856] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:32.856] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:125:0) after 1m0s
[23:18:32.882] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:125:0)
[23:18:32.882] Timer tick, asking Buildbucket for the build status
[23:18:32.969] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:32.969] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:126:0) after 1m0s
[23:19:32.995] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:126:0)
[23:19:32.995] Timer tick, asking Buildbucket for the build status
[23:19:33.083] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:33.083] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:127:0) after 1m0s
[23:20:33.117] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:127:0)
[23:20:33.117] Timer tick, asking Buildbucket for the build status
[23:20:33.227] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:33.227] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:128:0) after 1m0s
[23:21:33.278] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:128:0)
[23:21:33.278] Timer tick, asking Buildbucket for the build status
[23:21:33.357] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:33.357] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:129:0) after 1m0s
[23:22:33.382] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:129:0)
[23:22:33.382] Timer tick, asking Buildbucket for the build status
[23:22:33.481] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:33.481] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:130:0) after 1m0s
[23:23:33.565] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:130:0)
[23:23:33.565] Timer tick, asking Buildbucket for the build status
[23:23:33.659] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:33.659] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:131:0) after 1m0s
[23:24:33.685] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:131:0)
[23:24:33.685] Timer tick, asking Buildbucket for the build status
[23:24:33.764] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:33.764] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:132:0) after 1m0s
[23:25:33.788] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:132:0)
[23:25:33.788] Timer tick, asking Buildbucket for the build status
[23:25:33.893] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:33.893] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:133:0) after 1m0s
[23:26:33.918] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:133:0)
[23:26:33.918] Timer tick, asking Buildbucket for the build status
[23:26:34.032] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:34.032] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:134:0) after 1m0s
[23:27:34.079] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:134:0)
[23:27:34.079] Timer tick, asking Buildbucket for the build status
[23:27:34.202] Build 8867336388516650736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:34.202] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:135:0) after 1m0s
[23:28:04.782] Received PubSub notification, asking Buildbucket for the build status
[23:28:04.912] Build 8867336388516650736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:34.319] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:135:0)
[23:28:34.319] Timer tick, asking Buildbucket for the build status
[23:28:34.424] Build 8867336388516650736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:34.424] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:137:0) after 1m0s
[23:29:34.457] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:137:0)
[23:29:34.457] Timer tick, asking Buildbucket for the build status
[23:29:34.541] Build 8867336388516650736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:34.541] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:138:0) after 1m0s
[23:30:34.574] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:138:0)
[23:30:34.574] Timer tick, asking Buildbucket for the build status
[23:30:34.662] Build 8867336388516650736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:34.662] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:139:0) after 1m0s
[23:31:34.685] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:139:0)
[23:31:34.685] Timer tick, asking Buildbucket for the build status
[23:31:34.777] Build 8867336388516650736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:34.777] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:140:0) after 1m0s
[23:32:34.803] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:140:0)
[23:32:34.803] Timer tick, asking Buildbucket for the build status
[23:32:34.948] Build 8867336388516650736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:34.948] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:141:0) after 1m0s
[23:33:34.970] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:141:0)
[23:33:34.971] Timer tick, asking Buildbucket for the build status
[23:33:35.057] Build 8867336388516650736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:35.057] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:142:0) after 1m0s
[23:34:35.092] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:142:0)
[23:34:35.092] Timer tick, asking Buildbucket for the build status
[23:34:35.186] Build 8867336388516650736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:35.186] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:143:0) after 1m0s
[23:35:35.213] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:143:0)
[23:35:35.213] Timer tick, asking Buildbucket for the build status
[23:35:35.298] Build 8867336388516650736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:35.298] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:144:0) after 1m0s
[23:36:35.335] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:144:0)
[23:36:35.335] Timer tick, asking Buildbucket for the build status
[23:36:35.442] Build 8867336388516650736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:35.442] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:145:0) after 1m0s
[23:37:35.469] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:145:0)
[23:37:35.469] Timer tick, asking Buildbucket for the build status
[23:37:35.616] Build 8867336388516650736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:35.616] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:146:0) after 1m0s
[23:38:35.681] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:146:0)
[23:38:35.681] Timer tick, asking Buildbucket for the build status
[23:38:35.764] Build 8867336388516650736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:35.764] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:147:0) after 1m0s
[23:39:35.792] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:147:0)
[23:39:35.792] Timer tick, asking Buildbucket for the build status
[23:39:35.881] Build 8867336388516650736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:35.881] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:148:0) after 1m0s
[23:40:35.908] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:148:0)
[23:40:35.908] Timer tick, asking Buildbucket for the build status
[23:40:35.984] Build 8867336388516650736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:35.984] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:149:0) after 1m0s
[23:41:36.103] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:149:0)
[23:41:36.103] Timer tick, asking Buildbucket for the build status
[23:41:36.176] Build 8867336388516650736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:36.176] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:150:0) after 1m0s
[23:42:36.204] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:150:0)
[23:42:36.204] Timer tick, asking Buildbucket for the build status
[23:42:36.303] Build 8867336388516650736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:36.303] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:151:0) after 1m0s
[23:43:36.341] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:151:0)
[23:43:36.341] Timer tick, asking Buildbucket for the build status
[23:43:36.438] Build 8867336388516650736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:36.438] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:152:0) after 1m0s
[23:44:36.477] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:152:0)
[23:44:36.477] Timer tick, asking Buildbucket for the build status
[23:44:36.580] Build 8867336388516650736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:36.580] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032766450515813488:153:0) after 1m0s
[23:45:22.690] Received PubSub notification, asking Buildbucket for the build status
[23:45:22.824] Build 8867336388516650736: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:45:22.824] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1601855120087511",
"created_by": "project:infra",
"created_ts": "1601846053545078",
"id": "8867336388516650736",
"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\": \"4fef6fb9e50cba78822598a9f48a568c8c85337b\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4fef6fb9e50cba78822598a9f48a568c8c85337b\", \"title\": \"4fef6fb9e50cba78822598a9f48a568c8c85337b\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/4fef6fb9e50cba78822598a9f48a568c8c85337b\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"4fef6fb9e50cba78822598a9f48a568c8c85337b\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"4fef6fb9e50cba78822598a9f48a568c8c85337b\", \"got_revision_cp\": \"refs/heads/master@{#35529}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"git\", \"goma\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-r972\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5566-c267084\"], \"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": "1601854081866829",
"status": "COMPLETED",
"status_changed_ts": "1601855120382839",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17809",
"builder:infra-continuous-win10-64",
"buildset:commit/git/4fef6fb9e50cba78822598a9f48a568c8c85337b",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/4fef6fb9e50cba78822598a9f48a568c8c85337b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032766450515813488",
"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/8867336388516650736/+/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:4f0e4895af60c710",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1601855120393012",
"url": "https://luci-milo-dev.appspot.com/b/8867336388516650736",
"utcnow_ts": "1601855122816933"
}
}
[23:45:22.824] Invocation finished in 2h31m10.568800998s with status SUCCEEDED