[08:55:50.242] New invocation is queued and will start shortly
[08:55:51.326] Starting the invocation (attempt 1)
[08:55:51.365] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[08:55:51.368] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[08:55:51.369] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9035259022656860160",
"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\":\"6ec794cae9973f9940c3585d65280daac95647d6\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6ec794cae9973f9940c3585d65280daac95647d6\",\"title\":\"6ec794cae9973f9940c3585d65280daac95647d6\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/6ec794cae9973f9940c3585d65280daac95647d6\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"6ec794cae9973f9940c3585d65280daac95647d6\"}}",
"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:9035259022656860160",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/6ec794cae9973f9940c3585d65280daac95647d6",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/6ec794cae9973f9940c3585d65280daac95647d6",
"gitiles_ref:refs/heads/master"
]
}
[08:55:52.344] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1599468951490231",
"id": "8869828960680508896",
"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\": \"6ec794cae9973f9940c3585d65280daac95647d6\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6ec794cae9973f9940c3585d65280daac95647d6\", \"title\": \"6ec794cae9973f9940c3585d65280daac95647d6\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/6ec794cae9973f9940c3585d65280daac95647d6\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"6ec794cae9973f9940c3585d65280daac95647d6\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1599468952079972",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17662",
"builder:infra-continuous-win10-64",
"buildset:commit/git/6ec794cae9973f9940c3585d65280daac95647d6",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/6ec794cae9973f9940c3585d65280daac95647d6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035259022656860160",
"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/8869828960680508896/+/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": "1599468952080256",
"url": "https://luci-milo-dev.appspot.com/b/8869828960680508896",
"utcnow_ts": "1599468952297816"
}
}
[08:55:52.344] Task URL: https://luci-milo-dev.appspot.com/b/8869828960680508896
[08:55:52.344] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:2:0) after 1m0s
[08:56:52.379] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:2:0)
[08:56:52.379] Timer tick, asking Buildbucket for the build status
[08:56:52.543] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:56:52.543] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:3:0) after 1m0s
[08:57:52.590] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:3:0)
[08:57:52.590] Timer tick, asking Buildbucket for the build status
[08:57:52.707] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:57:52.707] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:4:0) after 1m0s
[08:58:52.783] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:4:0)
[08:58:52.783] Timer tick, asking Buildbucket for the build status
[08:58:53.013] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:58:53.013] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:5:0) after 1m0s
[08:59:53.089] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:5:0)
[08:59:53.089] Timer tick, asking Buildbucket for the build status
[08:59:53.284] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:59:53.284] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:6:0) after 1m0s
[09:00:53.305] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:6:0)
[09:00:53.305] Timer tick, asking Buildbucket for the build status
[09:00:53.450] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:00:53.450] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:7:0) after 1m0s
[09:01:53.478] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:7:0)
[09:01:53.478] Timer tick, asking Buildbucket for the build status
[09:01:53.652] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:01:53.652] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:8:0) after 1m0s
[09:02:53.679] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:8:0)
[09:02:53.679] Timer tick, asking Buildbucket for the build status
[09:02:53.810] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:02:53.810] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:9:0) after 1m0s
[09:03:53.834] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:9:0)
[09:03:53.834] Timer tick, asking Buildbucket for the build status
[09:03:53.982] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:03:53.982] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:10:0) after 1m0s
[09:04:54.086] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:10:0)
[09:04:54.086] Timer tick, asking Buildbucket for the build status
[09:04:54.223] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:04:54.223] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:11:0) after 1m0s
[09:05:54.246] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:11:0)
[09:05:54.246] Timer tick, asking Buildbucket for the build status
[09:05:54.418] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:05:54.418] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:12:0) after 1m0s
[09:06:54.441] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:12:0)
[09:06:54.441] Timer tick, asking Buildbucket for the build status
[09:06:54.605] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:06:54.605] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:13:0) after 1m0s
[09:07:54.633] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:13:0)
[09:07:54.633] Timer tick, asking Buildbucket for the build status
[09:07:54.809] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:07:54.809] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:14:0) after 1m0s
[09:08:54.886] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:14:0)
[09:08:54.886] Timer tick, asking Buildbucket for the build status
[09:08:55.073] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:08:55.073] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:15:0) after 1m0s
[09:09:55.182] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:15:0)
[09:09:55.182] Timer tick, asking Buildbucket for the build status
[09:09:55.368] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:09:55.368] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:16:0) after 1m0s
[09:10:55.486] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:16:0)
[09:10:55.486] Timer tick, asking Buildbucket for the build status
[09:10:55.636] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:10:55.636] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:17:0) after 1m0s
[09:11:55.678] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:17:0)
[09:11:55.678] Timer tick, asking Buildbucket for the build status
[09:11:55.792] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:11:55.792] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:18:0) after 1m0s
[09:12:55.816] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:18:0)
[09:12:55.816] Timer tick, asking Buildbucket for the build status
[09:12:55.932] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:12:55.932] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:19:0) after 1m0s
[09:13:55.988] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:19:0)
[09:13:55.988] Timer tick, asking Buildbucket for the build status
[09:13:56.190] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:13:56.190] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:20:0) after 1m0s
[09:14:56.280] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:20:0)
[09:14:56.280] Timer tick, asking Buildbucket for the build status
[09:14:56.442] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:14:56.442] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:21:0) after 1m0s
[09:15:56.461] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:21:0)
[09:15:56.461] Timer tick, asking Buildbucket for the build status
[09:15:56.617] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:15:56.617] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:22:0) after 1m0s
[09:16:56.739] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:22:0)
[09:16:56.739] Timer tick, asking Buildbucket for the build status
[09:16:56.999] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:16:56.999] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:23:0) after 1m0s
[09:17:57.192] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:23:0)
[09:17:57.192] Timer tick, asking Buildbucket for the build status
[09:17:57.371] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:17:57.371] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:24:0) after 1m0s
[09:18:57.424] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:24:0)
[09:18:57.424] Timer tick, asking Buildbucket for the build status
[09:18:57.597] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:18:57.597] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:25:0) after 1m0s
[09:19:57.621] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:25:0)
[09:19:57.621] Timer tick, asking Buildbucket for the build status
[09:19:57.811] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:19:57.811] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:26:0) after 1m0s
[09:20:57.883] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:26:0)
[09:20:57.883] Timer tick, asking Buildbucket for the build status
[09:20:58.040] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:20:58.040] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:27:0) after 1m0s
[09:21:58.090] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:27:0)
[09:21:58.090] Timer tick, asking Buildbucket for the build status
[09:21:58.257] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:21:58.257] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:28:0) after 1m0s
[09:22:58.315] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:28:0)
[09:22:58.315] Timer tick, asking Buildbucket for the build status
[09:22:58.523] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:22:58.523] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:29:0) after 1m0s
[09:23:58.598] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:29:0)
[09:23:58.598] Timer tick, asking Buildbucket for the build status
[09:23:58.732] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:23:58.732] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:30:0) after 1m0s
[09:24:58.754] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:30:0)
[09:24:58.754] Timer tick, asking Buildbucket for the build status
[09:24:58.890] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:24:58.968] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:31:0) after 1m0s
[09:25:59.091] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:31:0)
[09:25:59.091] Timer tick, asking Buildbucket for the build status
[09:25:59.322] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:25:59.322] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:32:0) after 1m0s
[09:26:59.343] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:32:0)
[09:26:59.343] Timer tick, asking Buildbucket for the build status
[09:26:59.476] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:26:59.476] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:33:0) after 1m0s
[09:27:59.585] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:33:0)
[09:27:59.585] Timer tick, asking Buildbucket for the build status
[09:27:59.761] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:27:59.761] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:34:0) after 1m0s
[09:28:59.802] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:34:0)
[09:28:59.802] Timer tick, asking Buildbucket for the build status
[09:29:00.148] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:29:00.148] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:35:0) after 1m0s
[09:30:00.180] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:35:0)
[09:30:00.180] Timer tick, asking Buildbucket for the build status
[09:30:00.399] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:30:00.399] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:36:0) after 1m0s
[09:31:00.432] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:36:0)
[09:31:00.432] Timer tick, asking Buildbucket for the build status
[09:31:00.561] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:31:00.561] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:37:0) after 1m0s
[09:32:00.586] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:37:0)
[09:32:00.586] Timer tick, asking Buildbucket for the build status
[09:32:00.763] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:32:00.763] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:38:0) after 1m0s
[09:33:00.815] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:38:0)
[09:33:00.815] Timer tick, asking Buildbucket for the build status
[09:33:00.964] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:33:00.964] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:39:0) after 1m0s
[09:34:01.075] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:39:0)
[09:34:01.075] Timer tick, asking Buildbucket for the build status
[09:34:01.193] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:34:01.193] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:40:0) after 1m0s
[09:35:01.216] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:40:0)
[09:35:01.216] Timer tick, asking Buildbucket for the build status
[09:35:01.381] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:35:01.381] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:41:0) after 1m0s
[09:36:01.410] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:41:0)
[09:36:01.410] Timer tick, asking Buildbucket for the build status
[09:36:01.577] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:36:01.577] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:42:0) after 1m0s
[09:37:01.599] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:42:0)
[09:37:01.599] Timer tick, asking Buildbucket for the build status
[09:37:01.766] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:37:01.766] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:43:0) after 1m0s
[09:38:01.789] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:43:0)
[09:38:01.789] Timer tick, asking Buildbucket for the build status
[09:38:01.917] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:38:01.917] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:44:0) after 1m0s
[09:39:01.988] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:44:0)
[09:39:01.988] Timer tick, asking Buildbucket for the build status
[09:39:02.149] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:39:02.149] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:45:0) after 1m0s
[09:40:02.171] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:45:0)
[09:40:02.171] Timer tick, asking Buildbucket for the build status
[09:40:02.334] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:40:02.334] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:46:0) after 1m0s
[09:41:02.359] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:46:0)
[09:41:02.359] Timer tick, asking Buildbucket for the build status
[09:41:02.535] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:41:02.535] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:47:0) after 1m0s
[09:42:02.567] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:47:0)
[09:42:02.567] Timer tick, asking Buildbucket for the build status
[09:42:02.751] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:42:02.751] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:48:0) after 1m0s
[09:43:02.774] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:48:0)
[09:43:02.774] Timer tick, asking Buildbucket for the build status
[09:43:02.952] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:43:02.952] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:49:0) after 1m0s
[09:44:02.975] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:49:0)
[09:44:02.975] Timer tick, asking Buildbucket for the build status
[09:44:03.153] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:44:03.153] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:50:0) after 1m0s
[09:45:03.197] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:50:0)
[09:45:03.197] Timer tick, asking Buildbucket for the build status
[09:45:03.420] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:45:03.420] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:51:0) after 1m0s
[09:46:03.456] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:51:0)
[09:46:03.456] Timer tick, asking Buildbucket for the build status
[09:46:03.639] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:46:03.639] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:52:0) after 1m0s
[09:47:03.660] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:52:0)
[09:47:03.660] Timer tick, asking Buildbucket for the build status
[09:47:03.790] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:47:03.790] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:53:0) after 1m0s
[09:48:03.812] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:53:0)
[09:48:03.812] Timer tick, asking Buildbucket for the build status
[09:48:03.986] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:48:03.986] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:54:0) after 1m0s
[09:49:04.013] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:54:0)
[09:49:04.013] Timer tick, asking Buildbucket for the build status
[09:49:04.191] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:49:04.192] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:55:0) after 1m0s
[09:50:04.225] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:55:0)
[09:50:04.225] Timer tick, asking Buildbucket for the build status
[09:50:04.383] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:50:04.383] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:56:0) after 1m0s
[09:51:04.405] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:56:0)
[09:51:04.405] Timer tick, asking Buildbucket for the build status
[09:51:04.563] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:51:04.563] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:57:0) after 1m0s
[09:52:04.686] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:57:0)
[09:52:04.686] Timer tick, asking Buildbucket for the build status
[09:52:04.880] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:52:04.880] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:58:0) after 1m0s
[09:53:04.992] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:58:0)
[09:53:04.992] Timer tick, asking Buildbucket for the build status
[09:53:05.111] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:53:05.111] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:59:0) after 1m0s
[09:54:05.188] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:59:0)
[09:54:05.188] Timer tick, asking Buildbucket for the build status
[09:54:05.399] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:54:05.399] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:60:0) after 1m0s
[09:55:05.422] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:60:0)
[09:55:05.422] Timer tick, asking Buildbucket for the build status
[09:55:05.546] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:55:05.546] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:61:0) after 1m0s
[09:56:05.568] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:61:0)
[09:56:05.568] Timer tick, asking Buildbucket for the build status
[09:56:05.772] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:56:05.772] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:62:0) after 1m0s
[09:57:05.799] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:62:0)
[09:57:05.799] Timer tick, asking Buildbucket for the build status
[09:57:05.958] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:57:05.958] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:63:0) after 1m0s
[09:58:05.979] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:63:0)
[09:58:05.979] Timer tick, asking Buildbucket for the build status
[09:58:06.152] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:58:06.152] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:64:0) after 1m0s
[09:59:06.176] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:64:0)
[09:59:06.176] Timer tick, asking Buildbucket for the build status
[09:59:06.278] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:59:06.278] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:65:0) after 1m0s
[10:00:06.306] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:65:0)
[10:00:06.306] Timer tick, asking Buildbucket for the build status
[10:00:06.426] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:00:06.426] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:66:0) after 1m0s
[10:01:06.449] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:66:0)
[10:01:06.449] Timer tick, asking Buildbucket for the build status
[10:01:06.577] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:01:06.577] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:67:0) after 1m0s
[10:02:06.602] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:67:0)
[10:02:06.602] Timer tick, asking Buildbucket for the build status
[10:02:06.762] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:02:06.762] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:68:0) after 1m0s
[10:03:06.783] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:68:0)
[10:03:06.783] Timer tick, asking Buildbucket for the build status
[10:03:07.034] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:03:07.035] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:69:0) after 1m0s
[10:04:07.101] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:69:0)
[10:04:07.101] Timer tick, asking Buildbucket for the build status
[10:04:07.262] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:04:07.262] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:70:0) after 1m0s
[10:05:07.298] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:70:0)
[10:05:07.298] Timer tick, asking Buildbucket for the build status
[10:05:07.493] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:05:07.493] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:71:0) after 1m0s
[10:06:07.576] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:71:0)
[10:06:07.576] Timer tick, asking Buildbucket for the build status
[10:06:07.789] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:06:07.789] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:72:0) after 1m0s
[10:07:07.896] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:72:0)
[10:07:07.896] Timer tick, asking Buildbucket for the build status
[10:07:08.175] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:07:08.175] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:73:0) after 1m0s
[10:08:08.385] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:73:0)
[10:08:08.385] Timer tick, asking Buildbucket for the build status
[10:08:08.604] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:08:08.604] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:74:0) after 1m0s
[10:09:08.703] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:74:0)
[10:09:08.703] Timer tick, asking Buildbucket for the build status
[10:09:08.841] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:09:08.841] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:75:0) after 1m0s
[10:10:08.891] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:75:0)
[10:10:08.891] Timer tick, asking Buildbucket for the build status
[10:10:09.072] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:10:09.072] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:76:0) after 1m0s
[10:11:09.118] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:76:0)
[10:11:09.118] Timer tick, asking Buildbucket for the build status
[10:11:09.320] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:11:09.320] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:77:0) after 1m0s
[10:12:09.484] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:77:0)
[10:12:09.484] Timer tick, asking Buildbucket for the build status
[10:12:09.604] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:12:09.604] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:78:0) after 1m0s
[10:13:09.665] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:78:0)
[10:13:09.665] Timer tick, asking Buildbucket for the build status
[10:13:09.781] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:13:09.781] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:79:0) after 1m0s
[10:14:09.929] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:79:0)
[10:14:09.929] Timer tick, asking Buildbucket for the build status
[10:14:10.032] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:14:10.032] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:80:0) after 1m0s
[10:15:10.058] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:80:0)
[10:15:10.058] Timer tick, asking Buildbucket for the build status
[10:15:10.196] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:15:10.196] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:81:0) after 1m0s
[10:16:10.291] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:81:0)
[10:16:10.291] Timer tick, asking Buildbucket for the build status
[10:16:10.430] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:16:10.430] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:82:0) after 1m0s
[10:17:10.456] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:82:0)
[10:17:10.456] Timer tick, asking Buildbucket for the build status
[10:17:10.559] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:17:10.559] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:83:0) after 1m0s
[10:18:10.643] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:83:0)
[10:18:10.643] Timer tick, asking Buildbucket for the build status
[10:18:10.868] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:18:10.868] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:84:0) after 1m0s
[10:19:11.076] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:84:0)
[10:19:11.076] Timer tick, asking Buildbucket for the build status
[10:19:11.200] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:19:11.200] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:85:0) after 1m0s
[10:20:11.231] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:85:0)
[10:20:11.231] Timer tick, asking Buildbucket for the build status
[10:20:11.319] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:20:11.319] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:86:0) after 1m0s
[10:21:11.381] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:86:0)
[10:21:11.381] Timer tick, asking Buildbucket for the build status
[10:21:11.484] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:21:11.484] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:87:0) after 1m0s
[10:22:11.537] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:87:0)
[10:22:11.537] Timer tick, asking Buildbucket for the build status
[10:22:11.721] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:22:11.721] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:88:0) after 1m0s
[10:23:11.908] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:88:0)
[10:23:11.908] Timer tick, asking Buildbucket for the build status
[10:23:12.037] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:23:12.037] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:89:0) after 1m0s
[10:24:12.075] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:89:0)
[10:24:12.075] Timer tick, asking Buildbucket for the build status
[10:24:12.230] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:24:12.230] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:90:0) after 1m0s
[10:25:12.261] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:90:0)
[10:25:12.261] Timer tick, asking Buildbucket for the build status
[10:25:12.417] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:25:12.417] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:91:0) after 1m0s
[10:26:12.446] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:91:0)
[10:26:12.446] Timer tick, asking Buildbucket for the build status
[10:26:12.552] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:26:12.552] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:92:0) after 1m0s
[10:27:12.624] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:92:0)
[10:27:12.624] Timer tick, asking Buildbucket for the build status
[10:27:12.809] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:27:12.809] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:93:0) after 1m0s
[10:28:12.902] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:93:0)
[10:28:12.902] Timer tick, asking Buildbucket for the build status
[10:28:12.989] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:28:12.989] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:94:0) after 1m0s
[10:29:13.133] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:94:0)
[10:29:13.133] Timer tick, asking Buildbucket for the build status
[10:29:13.237] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:29:13.237] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:95:0) after 1m0s
[10:30:13.418] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:95:0)
[10:30:13.418] Timer tick, asking Buildbucket for the build status
[10:30:13.544] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:30:13.544] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:96:0) after 1m0s
[10:31:13.626] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:96:0)
[10:31:13.626] Timer tick, asking Buildbucket for the build status
[10:31:13.754] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:31:13.754] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:97:0) after 1m0s
[10:32:13.864] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:97:0)
[10:32:13.864] Timer tick, asking Buildbucket for the build status
[10:32:14.042] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:32:14.042] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:98:0) after 1m0s
[10:33:14.100] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:98:0)
[10:33:14.100] Timer tick, asking Buildbucket for the build status
[10:33:14.228] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:33:14.228] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:99:0) after 1m0s
[10:34:14.261] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:99:0)
[10:34:14.261] Timer tick, asking Buildbucket for the build status
[10:34:14.392] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:34:14.392] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:100:0) after 1m0s
[10:35:14.482] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:100:0)
[10:35:14.482] Timer tick, asking Buildbucket for the build status
[10:35:14.600] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:35:14.600] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:101:0) after 1m0s
[10:36:14.638] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:101:0)
[10:36:14.638] Timer tick, asking Buildbucket for the build status
[10:36:14.766] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:36:14.766] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:102:0) after 1m0s
[10:37:14.905] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:102:0)
[10:37:14.905] Timer tick, asking Buildbucket for the build status
[10:37:15.033] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:37:15.033] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:103:0) after 1m0s
[10:38:15.058] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:103:0)
[10:38:15.058] Timer tick, asking Buildbucket for the build status
[10:38:15.212] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:38:15.212] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:104:0) after 1m0s
[10:39:15.302] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:104:0)
[10:39:15.302] Timer tick, asking Buildbucket for the build status
[10:39:15.687] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:39:15.687] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:105:0) after 1m0s
[10:40:15.760] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:105:0)
[10:40:15.760] Timer tick, asking Buildbucket for the build status
[10:40:15.909] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:40:15.909] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:106:0) after 1m0s
[10:41:15.994] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:106:0)
[10:41:15.994] Timer tick, asking Buildbucket for the build status
[10:41:16.244] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:41:16.244] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:107:0) after 1m0s
[10:42:16.267] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:107:0)
[10:42:16.267] Timer tick, asking Buildbucket for the build status
[10:42:16.380] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:42:16.380] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:108:0) after 1m0s
[10:43:16.404] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:108:0)
[10:43:16.404] Timer tick, asking Buildbucket for the build status
[10:43:16.485] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:43:16.485] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:109:0) after 1m0s
[10:44:16.522] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:109:0)
[10:44:16.522] Timer tick, asking Buildbucket for the build status
[10:44:16.641] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:44:16.641] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:110:0) after 1m0s
[10:45:16.693] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:110:0)
[10:45:16.693] Timer tick, asking Buildbucket for the build status
[10:45:16.809] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:45:16.809] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:111:0) after 1m0s
[10:46:16.901] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:111:0)
[10:46:16.901] Timer tick, asking Buildbucket for the build status
[10:46:17.168] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:46:17.168] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:112:0) after 1m0s
[10:47:17.240] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:112:0)
[10:47:17.240] Timer tick, asking Buildbucket for the build status
[10:47:17.418] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:47:17.418] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:113:0) after 1m0s
[10:48:17.479] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:113:0)
[10:48:17.479] Timer tick, asking Buildbucket for the build status
[10:48:17.650] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:48:17.650] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:114:0) after 1m0s
[10:49:17.681] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:114:0)
[10:49:17.681] Timer tick, asking Buildbucket for the build status
[10:49:17.840] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:49:17.840] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:115:0) after 1m0s
[10:50:17.993] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:115:0)
[10:50:17.993] Timer tick, asking Buildbucket for the build status
[10:50:18.285] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:50:18.285] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:116:0) after 1m0s
[10:51:18.395] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:116:0)
[10:51:18.395] Timer tick, asking Buildbucket for the build status
[10:51:18.538] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:51:18.538] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:117:0) after 1m0s
[10:52:18.585] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:117:0)
[10:52:18.585] Timer tick, asking Buildbucket for the build status
[10:52:18.709] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:52:18.709] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:118:0) after 1m0s
[10:53:18.736] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:118:0)
[10:53:18.736] Timer tick, asking Buildbucket for the build status
[10:53:18.894] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:53:18.894] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:119:0) after 1m0s
[10:54:18.920] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:119:0)
[10:54:18.920] Timer tick, asking Buildbucket for the build status
[10:54:19.123] Build 8869828960680508896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:54:19.123] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:120:0) after 1m0s
[10:55:00.122] Received PubSub notification, asking Buildbucket for the build status
[10:55:00.477] Build 8869828960680508896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:19.186] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:120:0)
[10:55:19.186] Timer tick, asking Buildbucket for the build status
[10:55:19.407] Build 8869828960680508896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:19.407] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:122:0) after 1m0s
[10:56:19.489] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:122:0)
[10:56:19.489] Timer tick, asking Buildbucket for the build status
[10:56:19.638] Build 8869828960680508896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:19.638] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:123:0) after 1m0s
[10:57:19.661] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:123:0)
[10:57:19.661] Timer tick, asking Buildbucket for the build status
[10:57:19.985] Build 8869828960680508896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:19.985] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:124:0) after 1m0s
[10:58:20.064] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:124:0)
[10:58:20.064] Timer tick, asking Buildbucket for the build status
[10:58:20.240] Build 8869828960680508896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:20.240] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:125:0) after 1m0s
[10:59:20.271] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:125:0)
[10:59:20.271] Timer tick, asking Buildbucket for the build status
[10:59:20.579] Build 8869828960680508896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:20.579] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:126:0) after 1m0s
[11:00:20.661] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:126:0)
[11:00:20.661] Timer tick, asking Buildbucket for the build status
[11:00:20.812] Build 8869828960680508896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:20.812] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:127:0) after 1m0s
[11:01:20.846] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:127:0)
[11:01:20.846] Timer tick, asking Buildbucket for the build status
[11:01:21.163] Build 8869828960680508896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:21.163] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:128:0) after 1m0s
[11:02:21.221] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:128:0)
[11:02:21.221] Timer tick, asking Buildbucket for the build status
[11:02:21.601] Build 8869828960680508896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:21.601] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:129:0) after 1m0s
[11:03:21.631] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:129:0)
[11:03:21.631] Timer tick, asking Buildbucket for the build status
[11:03:21.792] Build 8869828960680508896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:21.792] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9035259022656860160:130:0) after 1m0s
[11:04:20.091] Received PubSub notification, asking Buildbucket for the build status
[11:04:20.217] Build 8869828960680508896: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:04:20.217] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1599476658333800",
"created_by": "project:infra",
"created_ts": "1599468951490231",
"id": "8869828960680508896",
"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\": \"6ec794cae9973f9940c3585d65280daac95647d6\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6ec794cae9973f9940c3585d65280daac95647d6\", \"title\": \"6ec794cae9973f9940c3585d65280daac95647d6\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/6ec794cae9973f9940c3585d65280daac95647d6\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"6ec794cae9973f9940c3585d65280daac95647d6\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"6ec794cae9973f9940c3585d65280daac95647d6\", \"got_revision_cp\": \"refs/heads/master@{#34830}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_044445a3bc7a4bfe795493d7956aa02cd526172de1fa7300f69fe152e82ea631_v2\", \"builder_5de1032f0e9ef497433aad28840ee258c037976023b410f653133cee0ad04e2c_v2\", \"builder_82ed111fed7ea8169e482f00b5f3e5338a65daa41208585de67cb0a736b1d538_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"git\", \"goma\", \"infra_gclient_with_go\", \"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-57qm\"], \"image\": [\"chrome-win10-1703-20083100-22dcea0839e\"], \"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\": [\"5512-a93fb3c\"], \"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": "1599476097190550",
"status": "COMPLETED",
"status_changed_ts": "1599476658791184",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17662",
"builder:infra-continuous-win10-64",
"buildset:commit/git/6ec794cae9973f9940c3585d65280daac95647d6",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/6ec794cae9973f9940c3585d65280daac95647d6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035259022656860160",
"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/8869828960680508896/+/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:4e8098e300383510",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1599476658806048",
"url": "https://luci-milo-dev.appspot.com/b/8869828960680508896",
"utcnow_ts": "1599476660208433"
}
}
[11:04:20.217] Invocation finished in 2h8m29.99477946s with status SUCCEEDED