[08:51:28.152] New invocation is queued and will start shortly
[08:51:29.254] Starting the invocation (attempt 1)
[08:51:29.291] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[08:51:29.291] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[08:51:29.291] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9019314231389454560",
"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\":\"72a384e18e974320356dbb63a1f4cd6941c94a73\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@72a384e18e974320356dbb63a1f4cd6941c94a73\",\"title\":\"72a384e18e974320356dbb63a1f4cd6941c94a73\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/72a384e18e974320356dbb63a1f4cd6941c94a73\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"57f61ed3f50581b365369ecbca11a67d987e9f26\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@57f61ed3f50581b365369ecbca11a67d987e9f26\",\"title\":\"57f61ed3f50581b365369ecbca11a67d987e9f26\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/57f61ed3f50581b365369ecbca11a67d987e9f26\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"57f61ed3f50581b365369ecbca11a67d987e9f26\"}}",
"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:9019314231389454560",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/57f61ed3f50581b365369ecbca11a67d987e9f26",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/57f61ed3f50581b365369ecbca11a67d987e9f26",
"gitiles_ref:refs/heads/master"
]
}
[08:51:29.855] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1614675089363729",
"id": "8853884169454721040",
"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\": \"72a384e18e974320356dbb63a1f4cd6941c94a73\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@72a384e18e974320356dbb63a1f4cd6941c94a73\", \"title\": \"72a384e18e974320356dbb63a1f4cd6941c94a73\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/72a384e18e974320356dbb63a1f4cd6941c94a73\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"57f61ed3f50581b365369ecbca11a67d987e9f26\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@57f61ed3f50581b365369ecbca11a67d987e9f26\", \"title\": \"57f61ed3f50581b365369ecbca11a67d987e9f26\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/57f61ed3f50581b365369ecbca11a67d987e9f26\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"57f61ed3f50581b365369ecbca11a67d987e9f26\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614675089745247",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18753",
"builder:infra-continuous-win10-64",
"buildset:commit/git/57f61ed3f50581b365369ecbca11a67d987e9f26",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/57f61ed3f50581b365369ecbca11a67d987e9f26",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019314231389454560",
"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/8853884169454721040/+/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": "1614675089745350",
"url": "https://luci-milo-dev.appspot.com/b/8853884169454721040",
"utcnow_ts": "1614675089848463"
}
}
[08:51:29.855] Task URL: https://luci-milo-dev.appspot.com/b/8853884169454721040
[08:51:29.855] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:2:0) after 1m0s
[08:52:29.943] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:2:0)
[08:52:29.943] Timer tick, asking Buildbucket for the build status
[08:52:30.024] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:52:30.024] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:3:0) after 1m0s
[08:53:30.052] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:3:0)
[08:53:30.052] Timer tick, asking Buildbucket for the build status
[08:53:30.185] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:53:30.185] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:4:0) after 1m0s
[08:54:30.209] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:4:0)
[08:54:30.209] Timer tick, asking Buildbucket for the build status
[08:54:30.277] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:54:30.277] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:5:0) after 1m0s
[08:55:30.298] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:5:0)
[08:55:30.298] Timer tick, asking Buildbucket for the build status
[08:55:30.377] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:55:30.377] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:6:0) after 1m0s
[08:56:30.393] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:6:0)
[08:56:30.393] Timer tick, asking Buildbucket for the build status
[08:56:30.497] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:56:30.497] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:7:0) after 1m0s
[08:57:30.574] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:7:0)
[08:57:30.574] Timer tick, asking Buildbucket for the build status
[08:57:30.680] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:57:30.680] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:8:0) after 1m0s
[08:58:30.714] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:8:0)
[08:58:30.714] Timer tick, asking Buildbucket for the build status
[08:58:30.774] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:58:30.774] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:9:0) after 1m0s
[08:59:30.870] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:9:0)
[08:59:30.870] Timer tick, asking Buildbucket for the build status
[08:59:30.936] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:59:30.936] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:10:0) after 1m0s
[09:00:30.953] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:10:0)
[09:00:30.953] Timer tick, asking Buildbucket for the build status
[09:00:31.033] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:00:31.033] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:11:0) after 1m0s
[09:01:31.051] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:11:0)
[09:01:31.051] Timer tick, asking Buildbucket for the build status
[09:01:31.124] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:01:31.124] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:12:0) after 1m0s
[09:02:31.250] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:12:0)
[09:02:31.250] Timer tick, asking Buildbucket for the build status
[09:02:31.316] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:02:31.316] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:13:0) after 1m0s
[09:03:31.332] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:13:0)
[09:03:31.333] Timer tick, asking Buildbucket for the build status
[09:03:31.397] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:03:31.397] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:14:0) after 1m0s
[09:04:31.416] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:14:0)
[09:04:31.416] Timer tick, asking Buildbucket for the build status
[09:04:31.491] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:04:31.491] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:15:0) after 1m0s
[09:05:31.552] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:15:0)
[09:05:31.553] Timer tick, asking Buildbucket for the build status
[09:05:31.612] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:05:31.612] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:16:0) after 1m0s
[09:06:31.710] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:16:0)
[09:06:31.711] Timer tick, asking Buildbucket for the build status
[09:06:31.778] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:06:31.778] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:17:0) after 1m0s
[09:07:31.818] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:17:0)
[09:07:31.818] Timer tick, asking Buildbucket for the build status
[09:07:31.890] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:07:31.890] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:18:0) after 1m0s
[09:08:31.913] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:18:0)
[09:08:31.913] Timer tick, asking Buildbucket for the build status
[09:08:31.985] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:08:31.985] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:19:0) after 1m0s
[09:09:32.013] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:19:0)
[09:09:32.013] Timer tick, asking Buildbucket for the build status
[09:09:32.081] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:09:32.081] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:20:0) after 1m0s
[09:10:32.101] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:20:0)
[09:10:32.101] Timer tick, asking Buildbucket for the build status
[09:10:32.173] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:10:32.173] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:21:0) after 1m0s
[09:11:32.200] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:21:0)
[09:11:32.200] Timer tick, asking Buildbucket for the build status
[09:11:32.278] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:11:32.278] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:22:0) after 1m0s
[09:12:32.303] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:22:0)
[09:12:32.303] Timer tick, asking Buildbucket for the build status
[09:12:32.373] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:12:32.373] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:23:0) after 1m0s
[09:13:32.392] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:23:0)
[09:13:32.392] Timer tick, asking Buildbucket for the build status
[09:13:32.476] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:13:32.476] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:24:0) after 1m0s
[09:14:32.497] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:24:0)
[09:14:32.497] Timer tick, asking Buildbucket for the build status
[09:14:32.574] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:14:32.574] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:25:0) after 1m0s
[09:15:32.655] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:25:0)
[09:15:32.655] Timer tick, asking Buildbucket for the build status
[09:15:32.718] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:15:32.718] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:26:0) after 1m0s
[09:16:32.802] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:26:0)
[09:16:32.802] Timer tick, asking Buildbucket for the build status
[09:16:32.862] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:16:32.862] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:27:0) after 1m0s
[09:17:32.910] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:27:0)
[09:17:32.910] Timer tick, asking Buildbucket for the build status
[09:17:33.160] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:17:33.160] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:28:0) after 1m0s
[09:18:33.279] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:28:0)
[09:18:33.280] Timer tick, asking Buildbucket for the build status
[09:18:33.342] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:18:33.342] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:29:0) after 1m0s
[09:19:33.372] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:29:0)
[09:19:33.372] Timer tick, asking Buildbucket for the build status
[09:19:33.594] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:19:33.594] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:30:0) after 1m0s
[09:20:33.638] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:30:0)
[09:20:33.638] Timer tick, asking Buildbucket for the build status
[09:20:33.765] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:20:33.765] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:31:0) after 1m0s
[09:21:33.801] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:31:0)
[09:21:33.801] Timer tick, asking Buildbucket for the build status
[09:21:33.885] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:21:33.886] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:32:0) after 1m0s
[09:22:33.910] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:32:0)
[09:22:33.910] Timer tick, asking Buildbucket for the build status
[09:22:33.973] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:22:33.973] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:33:0) after 1m0s
[09:23:33.997] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:33:0)
[09:23:33.997] Timer tick, asking Buildbucket for the build status
[09:23:34.072] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:23:34.072] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:34:0) after 1m0s
[09:24:34.113] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:34:0)
[09:24:34.113] Timer tick, asking Buildbucket for the build status
[09:24:34.192] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:24:34.192] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:35:0) after 1m0s
[09:25:34.219] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:35:0)
[09:25:34.219] Timer tick, asking Buildbucket for the build status
[09:25:34.300] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:25:34.300] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:36:0) after 1m0s
[09:26:34.331] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:36:0)
[09:26:34.331] Timer tick, asking Buildbucket for the build status
[09:26:34.401] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:26:34.401] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:37:0) after 1m0s
[09:27:34.454] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:37:0)
[09:27:34.454] Timer tick, asking Buildbucket for the build status
[09:27:34.574] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:27:34.574] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:38:0) after 1m0s
[09:28:34.621] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:38:0)
[09:28:34.621] Timer tick, asking Buildbucket for the build status
[09:28:34.693] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:28:34.693] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:39:0) after 1m0s
[09:29:34.761] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:39:0)
[09:29:34.761] Timer tick, asking Buildbucket for the build status
[09:29:34.828] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:29:34.828] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:40:0) after 1m0s
[09:30:34.915] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:40:0)
[09:30:34.915] Timer tick, asking Buildbucket for the build status
[09:30:34.996] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:30:34.996] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:41:0) after 1m0s
[09:31:35.117] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:41:0)
[09:31:35.117] Timer tick, asking Buildbucket for the build status
[09:31:35.243] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:31:35.243] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:42:0) after 1m0s
[09:32:35.348] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:42:0)
[09:32:35.348] Timer tick, asking Buildbucket for the build status
[09:32:35.413] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:32:35.413] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:43:0) after 1m0s
[09:33:35.452] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:43:0)
[09:33:35.452] Timer tick, asking Buildbucket for the build status
[09:33:35.520] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:33:35.520] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:44:0) after 1m0s
[09:34:35.546] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:44:0)
[09:34:35.546] Timer tick, asking Buildbucket for the build status
[09:34:35.625] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:34:35.625] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:45:0) after 1m0s
[09:35:35.663] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:45:0)
[09:35:35.663] Timer tick, asking Buildbucket for the build status
[09:35:35.725] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:35:35.725] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:46:0) after 1m0s
[09:36:35.757] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:46:0)
[09:36:35.758] Timer tick, asking Buildbucket for the build status
[09:36:35.827] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:36:35.827] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:47:0) after 1m0s
[09:37:35.855] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:47:0)
[09:37:35.855] Timer tick, asking Buildbucket for the build status
[09:37:35.924] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:37:35.924] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:48:0) after 1m0s
[09:38:35.960] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:48:0)
[09:38:35.960] Timer tick, asking Buildbucket for the build status
[09:38:36.018] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:38:36.018] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:49:0) after 1m0s
[09:39:36.112] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:49:0)
[09:39:36.112] Timer tick, asking Buildbucket for the build status
[09:39:36.181] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:39:36.181] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:50:0) after 1m0s
[09:40:36.220] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:50:0)
[09:40:36.220] Timer tick, asking Buildbucket for the build status
[09:40:36.297] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:40:36.297] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:51:0) after 1m0s
[09:41:36.369] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:51:0)
[09:41:36.369] Timer tick, asking Buildbucket for the build status
[09:41:36.483] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:41:36.483] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:52:0) after 1m0s
[09:42:36.559] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:52:0)
[09:42:36.559] Timer tick, asking Buildbucket for the build status
[09:42:36.634] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:42:36.634] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:53:0) after 1m0s
[09:43:36.702] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:53:0)
[09:43:36.702] Timer tick, asking Buildbucket for the build status
[09:43:36.764] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:43:36.764] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:54:0) after 1m0s
[09:44:36.846] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:54:0)
[09:44:36.846] Timer tick, asking Buildbucket for the build status
[09:44:36.902] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:44:36.902] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:55:0) after 1m0s
[09:45:37.012] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:55:0)
[09:45:37.012] Timer tick, asking Buildbucket for the build status
[09:45:37.079] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:45:37.079] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:56:0) after 1m0s
[09:46:37.100] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:56:0)
[09:46:37.100] Timer tick, asking Buildbucket for the build status
[09:46:37.177] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:46:37.177] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:57:0) after 1m0s
[09:47:37.257] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:57:0)
[09:47:37.257] Timer tick, asking Buildbucket for the build status
[09:47:37.397] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:47:37.397] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:58:0) after 1m0s
[09:48:37.513] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:58:0)
[09:48:37.513] Timer tick, asking Buildbucket for the build status
[09:48:37.568] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:48:37.569] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:59:0) after 1m0s
[09:49:37.602] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:59:0)
[09:49:37.602] Timer tick, asking Buildbucket for the build status
[09:49:37.661] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:49:37.661] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:60:0) after 1m0s
[09:50:37.716] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:60:0)
[09:50:37.716] Timer tick, asking Buildbucket for the build status
[09:50:37.782] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:50:37.782] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:61:0) after 1m0s
[09:51:37.810] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:61:0)
[09:51:37.810] Timer tick, asking Buildbucket for the build status
[09:51:37.878] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:51:37.878] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:62:0) after 1m0s
[09:52:37.947] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:62:0)
[09:52:37.947] Timer tick, asking Buildbucket for the build status
[09:52:38.008] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:52:38.008] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:63:0) after 1m0s
[09:53:38.055] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:63:0)
[09:53:38.055] Timer tick, asking Buildbucket for the build status
[09:53:38.118] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:53:38.118] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:64:0) after 1m0s
[09:54:38.138] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:64:0)
[09:54:38.138] Timer tick, asking Buildbucket for the build status
[09:54:38.206] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:54:38.206] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:65:0) after 1m0s
[09:55:38.263] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:65:0)
[09:55:38.263] Timer tick, asking Buildbucket for the build status
[09:55:38.327] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:55:38.327] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:66:0) after 1m0s
[09:56:38.414] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:66:0)
[09:56:38.414] Timer tick, asking Buildbucket for the build status
[09:56:38.499] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:56:38.499] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:67:0) after 1m0s
[09:57:38.608] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:67:0)
[09:57:38.608] Timer tick, asking Buildbucket for the build status
[09:57:38.689] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:57:38.689] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:68:0) after 1m0s
[09:58:38.707] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:68:0)
[09:58:38.707] Timer tick, asking Buildbucket for the build status
[09:58:38.801] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:58:38.801] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:69:0) after 1m0s
[09:59:38.847] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:69:0)
[09:59:38.847] Timer tick, asking Buildbucket for the build status
[09:59:38.925] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:59:38.925] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:70:0) after 1m0s
[10:00:38.973] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:70:0)
[10:00:38.973] Timer tick, asking Buildbucket for the build status
[10:00:39.052] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:00:39.052] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:71:0) after 1m0s
[10:01:39.072] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:71:0)
[10:01:39.072] Timer tick, asking Buildbucket for the build status
[10:01:39.139] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:01:39.139] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:72:0) after 1m0s
[10:02:39.215] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:72:0)
[10:02:39.215] Timer tick, asking Buildbucket for the build status
[10:02:39.277] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:02:39.277] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:73:0) after 1m0s
[10:03:39.297] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:73:0)
[10:03:39.297] Timer tick, asking Buildbucket for the build status
[10:03:39.361] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:03:39.361] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:74:0) after 1m0s
[10:04:39.415] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:74:0)
[10:04:39.415] Timer tick, asking Buildbucket for the build status
[10:04:39.489] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:04:39.489] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:75:0) after 1m0s
[10:05:39.507] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:75:0)
[10:05:39.507] Timer tick, asking Buildbucket for the build status
[10:05:39.594] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:05:39.594] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:76:0) after 1m0s
[10:06:39.616] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:76:0)
[10:06:39.616] Timer tick, asking Buildbucket for the build status
[10:06:39.679] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:06:39.679] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:77:0) after 1m0s
[10:07:39.723] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:77:0)
[10:07:39.723] Timer tick, asking Buildbucket for the build status
[10:07:39.797] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:07:39.797] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:78:0) after 1m0s
[10:08:39.912] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:78:0)
[10:08:39.912] Timer tick, asking Buildbucket for the build status
[10:08:40.010] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:08:40.010] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:79:0) after 1m0s
[10:09:40.120] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:79:0)
[10:09:40.120] Timer tick, asking Buildbucket for the build status
[10:09:40.178] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:09:40.178] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:80:0) after 1m0s
[10:10:40.247] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:80:0)
[10:10:40.247] Timer tick, asking Buildbucket for the build status
[10:10:40.319] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:10:40.319] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:81:0) after 1m0s
[10:11:40.341] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:81:0)
[10:11:40.341] Timer tick, asking Buildbucket for the build status
[10:11:40.483] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:11:40.483] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:82:0) after 1m0s
[10:12:40.513] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:82:0)
[10:12:40.513] Timer tick, asking Buildbucket for the build status
[10:12:40.616] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:12:40.616] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:83:0) after 1m0s
[10:13:40.714] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:83:0)
[10:13:40.714] Timer tick, asking Buildbucket for the build status
[10:13:40.774] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:13:40.774] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:84:0) after 1m0s
[10:14:40.848] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:84:0)
[10:14:40.848] Timer tick, asking Buildbucket for the build status
[10:14:40.981] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:14:40.981] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:85:0) after 1m0s
[10:15:41.047] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:85:0)
[10:15:41.047] Timer tick, asking Buildbucket for the build status
[10:15:41.107] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:15:41.107] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:86:0) after 1m0s
[10:16:41.203] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:86:0)
[10:16:41.203] Timer tick, asking Buildbucket for the build status
[10:16:41.264] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:16:41.264] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:87:0) after 1m0s
[10:17:41.321] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:87:0)
[10:17:41.321] Timer tick, asking Buildbucket for the build status
[10:17:41.384] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:17:41.384] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:88:0) after 1m0s
[10:18:41.404] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:88:0)
[10:18:41.404] Timer tick, asking Buildbucket for the build status
[10:18:41.499] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:18:41.499] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:89:0) after 1m0s
[10:19:41.519] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:89:0)
[10:19:41.519] Timer tick, asking Buildbucket for the build status
[10:19:41.595] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:19:41.595] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:90:0) after 1m0s
[10:20:41.627] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:90:0)
[10:20:41.627] Timer tick, asking Buildbucket for the build status
[10:20:41.688] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:20:41.688] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:91:0) after 1m0s
[10:21:41.759] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:91:0)
[10:21:41.759] Timer tick, asking Buildbucket for the build status
[10:21:41.818] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:21:41.818] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:92:0) after 1m0s
[10:22:41.919] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:92:0)
[10:22:41.919] Timer tick, asking Buildbucket for the build status
[10:22:42.011] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:22:42.011] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:93:0) after 1m0s
[10:23:42.118] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:93:0)
[10:23:42.118] Timer tick, asking Buildbucket for the build status
[10:23:42.264] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:23:42.264] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:94:0) after 1m0s
[10:24:42.350] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:94:0)
[10:24:42.350] Timer tick, asking Buildbucket for the build status
[10:24:42.428] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:24:42.428] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:95:0) after 1m0s
[10:25:42.459] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:95:0)
[10:25:42.459] Timer tick, asking Buildbucket for the build status
[10:25:42.591] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:25:42.591] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:96:0) after 1m0s
[10:26:42.646] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:96:0)
[10:26:42.646] Timer tick, asking Buildbucket for the build status
[10:26:42.779] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:26:42.779] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:97:0) after 1m0s
[10:27:42.861] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:97:0)
[10:27:42.861] Timer tick, asking Buildbucket for the build status
[10:27:42.932] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:27:42.932] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:98:0) after 1m0s
[10:28:43.024] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:98:0)
[10:28:43.024] Timer tick, asking Buildbucket for the build status
[10:28:43.118] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:28:43.118] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:99:0) after 1m0s
[10:29:43.150] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:99:0)
[10:29:43.150] Timer tick, asking Buildbucket for the build status
[10:29:43.214] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:29:43.214] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:100:0) after 1m0s
[10:30:43.305] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:100:0)
[10:30:43.305] Timer tick, asking Buildbucket for the build status
[10:30:43.380] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:30:43.380] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:101:0) after 1m0s
[10:31:43.417] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:101:0)
[10:31:43.417] Timer tick, asking Buildbucket for the build status
[10:31:43.493] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:31:43.493] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:102:0) after 1m0s
[10:32:43.516] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:102:0)
[10:32:43.516] Timer tick, asking Buildbucket for the build status
[10:32:43.579] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:32:43.579] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:103:0) after 1m0s
[10:33:43.599] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:103:0)
[10:33:43.599] Timer tick, asking Buildbucket for the build status
[10:33:43.652] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:33:43.652] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:104:0) after 1m0s
[10:34:43.711] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:104:0)
[10:34:43.711] Timer tick, asking Buildbucket for the build status
[10:34:43.775] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:34:43.775] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:105:0) after 1m0s
[10:35:43.803] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:105:0)
[10:35:43.803] Timer tick, asking Buildbucket for the build status
[10:35:43.865] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:35:43.865] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:106:0) after 1m0s
[10:36:43.907] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:106:0)
[10:36:43.907] Timer tick, asking Buildbucket for the build status
[10:36:44.005] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:36:44.005] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:107:0) after 1m0s
[10:37:44.062] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:107:0)
[10:37:44.062] Timer tick, asking Buildbucket for the build status
[10:37:44.120] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:37:44.120] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:108:0) after 1m0s
[10:38:44.146] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:108:0)
[10:38:44.146] Timer tick, asking Buildbucket for the build status
[10:38:44.204] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:38:44.204] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:109:0) after 1m0s
[10:39:44.228] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:109:0)
[10:39:44.228] Timer tick, asking Buildbucket for the build status
[10:39:44.291] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:39:44.291] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:110:0) after 1m0s
[10:40:44.312] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:110:0)
[10:40:44.312] Timer tick, asking Buildbucket for the build status
[10:40:44.375] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:40:44.375] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:111:0) after 1m0s
[10:41:44.395] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:111:0)
[10:41:44.395] Timer tick, asking Buildbucket for the build status
[10:41:44.776] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:41:44.776] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:112:0) after 1m0s
[10:42:44.848] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:112:0)
[10:42:44.848] Timer tick, asking Buildbucket for the build status
[10:42:44.915] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:42:44.915] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:113:0) after 1m0s
[10:43:44.948] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:113:0)
[10:43:44.948] Timer tick, asking Buildbucket for the build status
[10:43:45.023] Build 8853884169454721040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:43:45.024] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:114:0) after 1m0s
[10:44:28.025] Received PubSub notification, asking Buildbucket for the build status
[10:44:28.086] Build 8853884169454721040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:45.051] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:114:0)
[10:44:45.051] Timer tick, asking Buildbucket for the build status
[10:44:45.122] Build 8853884169454721040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:45.122] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:116:0) after 1m0s
[10:45:45.151] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:116:0)
[10:45:45.151] Timer tick, asking Buildbucket for the build status
[10:45:45.209] Build 8853884169454721040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:45.209] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:117:0) after 1m0s
[10:46:45.274] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:117:0)
[10:46:45.274] Timer tick, asking Buildbucket for the build status
[10:46:45.343] Build 8853884169454721040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:45.343] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:118:0) after 1m0s
[10:47:45.458] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:118:0)
[10:47:45.458] Timer tick, asking Buildbucket for the build status
[10:47:45.582] Build 8853884169454721040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:45.582] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:119:0) after 1m0s
[10:48:45.617] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:119:0)
[10:48:45.617] Timer tick, asking Buildbucket for the build status
[10:48:45.692] Build 8853884169454721040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:45.692] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:120:0) after 1m0s
[10:49:45.721] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:120:0)
[10:49:45.721] Timer tick, asking Buildbucket for the build status
[10:49:45.795] Build 8853884169454721040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:45.795] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019314231389454560:121:0) after 1m0s
[10:50:37.562] Received PubSub notification, asking Buildbucket for the build status
[10:50:37.683] Build 8853884169454721040: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:50:37.683] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1614682237278491",
"created_by": "project:infra",
"created_ts": "1614675089363729",
"id": "8853884169454721040",
"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\": \"72a384e18e974320356dbb63a1f4cd6941c94a73\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@72a384e18e974320356dbb63a1f4cd6941c94a73\", \"title\": \"72a384e18e974320356dbb63a1f4cd6941c94a73\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/72a384e18e974320356dbb63a1f4cd6941c94a73\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"57f61ed3f50581b365369ecbca11a67d987e9f26\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@57f61ed3f50581b365369ecbca11a67d987e9f26\", \"title\": \"57f61ed3f50581b365369ecbca11a67d987e9f26\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/57f61ed3f50581b365369ecbca11a67d987e9f26\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"57f61ed3f50581b365369ecbca11a67d987e9f26\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"57f61ed3f50581b365369ecbca11a67d987e9f26\", \"got_revision_cp\": \"refs/heads/master@{#38968}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_044445a3bc7a4bfe795493d7956aa02cd526172de1fa7300f69fe152e82ea631_v2\", \"builder_5de1032f0e9ef497433aad28840ee258c037976023b410f653133cee0ad04e2c_v2\", \"builder_82ed111fed7ea8169e482f00b5f3e5338a65daa41208585de67cb0a736b1d538_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"builder_d38bdc976f893fc6930479dce8a6d7dc793b2a74fe1392d4af00713a5c0bb46e_v2\", \"git\", \"goma\", \"infra_gclient_with_go\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-fl15\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5728-2109f40\"], \"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": "1614681862128710",
"status": "COMPLETED",
"status_changed_ts": "1614681866729599",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18753",
"builder:infra-continuous-win10-64",
"buildset:commit/git/57f61ed3f50581b365369ecbca11a67d987e9f26",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/57f61ed3f50581b365369ecbca11a67d987e9f26",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019314231389454560",
"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/8853884169454721040/+/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:520af4221db91310",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1614682237278853",
"url": "https://luci-milo-dev.appspot.com/b/8853884169454721040",
"utcnow_ts": "1614682237676812"
}
}
[10:50:37.683] Invocation finished in 1h59m9.549373608s with status SUCCEEDED