[19:49:04.118] New invocation is queued and will start shortly
[19:49:05.396] Starting the invocation (attempt 1)
[19:49:05.445] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[19:49:05.445] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[19:49:05.445] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9099269980140313536",
"parameters_json": "{\"builder_name\":\"infra-continuous-win10-64\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra.git\",\"revision\":\"e103a6744e0d6380e29650acf3ee3e7cbaf071a8\"}}",
"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:9099269980140313536",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/e103a6744e0d6380e29650acf3ee3e7cbaf071a8",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/e103a6744e0d6380e29650acf3ee3e7cbaf071a8",
"gitiles_ref:refs/heads/master"
]
}
[19:49:06.655] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler-dev@appspot.gserviceaccount.com",
"created_ts": "1538423345556288",
"id": "8933839917968087168",
"lease_expiration_ts": "1538515146356596",
"lease_key": "1148094644",
"parameters_json": "{\"builder_name\": \"infra-continuous-win10-64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra.git\", \"revision\": \"e103a6744e0d6380e29650acf3ee3e7cbaf071a8\"}}",
"project": "infra",
"result_details_json": "null",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1538423346561285",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/4564",
"builder:infra-continuous-win10-64",
"buildset:commit/git/e103a6744e0d6380e29650acf3ee3e7cbaf071a8",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/e103a6744e0d6380e29650acf3ee3e7cbaf071a8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9099269980140313536",
"scheduler_job_id:infra/infra-continuous-win10-64",
"swarming_dimension:caches:builder_7785915434d7e14cb476edecfeb7743101007da4f242b5bf463abf3eb5feb198_v2",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:Chrome",
"swarming_hostname:chromium-swarm-dev.appspot.com",
"swarming_tag:allow_milo:1",
"swarming_tag:build_address:luci.infra.ci/infra-continuous-win10-64/4564",
"swarming_tag:buildbucket_bucket:luci.infra.ci",
"swarming_tag:buildbucket_build_id:8933839917968087168",
"swarming_tag:buildbucket_hostname:cr-buildbucket-dev.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:1630ff158d8d4118027817e4d74c356b46464ed9",
"swarming_tag:builder:infra-continuous-win10-64",
"swarming_tag:buildset:commit/git/e103a6744e0d6380e29650acf3ee3e7cbaf071a8",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/e103a6744e0d6380e29650acf3ee3e7cbaf071a8",
"swarming_tag:caches:builder_7785915434d7e14cb476edecfeb7743101007da4f242b5bf463abf3eb5feb198_v2",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://luci-logdog-dev.appspot.com/infra/buildbucket/cr-buildbucket-dev.appspot.com/8933839917968087168/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:Chrome",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:infra_continuous",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_tag:scheduler_invocation_id:9099269980140313536",
"swarming_tag:scheduler_job_id:infra/infra-continuous-win10-64",
"swarming_tag:service_account:adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:prod",
"swarming_tag:swarming.pool.version:419c10a306b2ebebcabc2bb490ca6fce54e43049",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler-dev",
"swarming_task_id:4049fedb89d28410",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1538423346561354",
"url": "https://luci-milo-dev.appspot.com/p/infra/builders/luci.infra.ci/infra-continuous-win10-64/4564",
"utcnow_ts": "1538423346639187"
}
}
[19:49:06.655] Task URL: https://luci-milo-dev.appspot.com/p/infra/builders/luci.infra.ci/infra-continuous-win10-64/4564
[19:49:06.655] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:2:0) after 1m0s
[19:50:06.675] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:2:0)
[19:50:06.675] Timer tick, asking Buildbucket for the build status
[19:50:06.754] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:50:06.754] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:3:0) after 1m0s
[19:51:06.769] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:3:0)
[19:51:06.769] Timer tick, asking Buildbucket for the build status
[19:51:06.849] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:51:06.849] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:4:0) after 1m0s
[19:52:06.876] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:4:0)
[19:52:06.876] Timer tick, asking Buildbucket for the build status
[19:52:06.953] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:52:06.953] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:5:0) after 1m0s
[19:53:06.969] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:5:0)
[19:53:06.969] Timer tick, asking Buildbucket for the build status
[19:53:07.051] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:53:07.051] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:6:0) after 1m0s
[19:54:07.075] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:6:0)
[19:54:07.075] Timer tick, asking Buildbucket for the build status
[19:54:07.149] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:54:07.149] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:7:0) after 1m0s
[19:55:07.168] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:7:0)
[19:55:07.168] Timer tick, asking Buildbucket for the build status
[19:55:07.238] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:55:07.238] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:8:0) after 1m0s
[19:56:07.252] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:8:0)
[19:56:07.252] Timer tick, asking Buildbucket for the build status
[19:56:07.323] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:56:07.323] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:9:0) after 1m0s
[19:57:07.340] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:9:0)
[19:57:07.340] Timer tick, asking Buildbucket for the build status
[19:57:07.405] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:57:07.405] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:10:0) after 1m0s
[19:58:07.430] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:10:0)
[19:58:07.430] Timer tick, asking Buildbucket for the build status
[19:58:07.509] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:58:07.509] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:11:0) after 1m0s
[19:59:07.525] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:11:0)
[19:59:07.525] Timer tick, asking Buildbucket for the build status
[19:59:07.602] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:59:07.602] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:12:0) after 1m0s
[20:00:07.653] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:12:0)
[20:00:07.653] Timer tick, asking Buildbucket for the build status
[20:00:07.728] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:00:07.728] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:13:0) after 1m0s
[20:01:07.746] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:13:0)
[20:01:07.746] Timer tick, asking Buildbucket for the build status
[20:01:07.899] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:01:07.899] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:14:0) after 1m0s
[20:02:07.951] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:14:0)
[20:02:07.951] Timer tick, asking Buildbucket for the build status
[20:02:08.030] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:02:08.030] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:15:0) after 1m0s
[20:03:08.067] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:15:0)
[20:03:08.067] Timer tick, asking Buildbucket for the build status
[20:03:08.171] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:03:08.171] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:16:0) after 1m0s
[20:04:08.249] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:16:0)
[20:04:08.249] Timer tick, asking Buildbucket for the build status
[20:04:08.322] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:04:08.322] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:17:0) after 1m0s
[20:05:08.347] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:17:0)
[20:05:08.347] Timer tick, asking Buildbucket for the build status
[20:05:08.583] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:05:08.583] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:18:0) after 1m0s
[20:06:08.602] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:18:0)
[20:06:08.602] Timer tick, asking Buildbucket for the build status
[20:06:08.686] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:06:08.686] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:19:0) after 1m0s
[20:07:08.707] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:19:0)
[20:07:08.707] Timer tick, asking Buildbucket for the build status
[20:07:08.775] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:07:08.775] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:20:0) after 1m0s
[20:08:08.797] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:20:0)
[20:08:08.797] Timer tick, asking Buildbucket for the build status
[20:08:08.893] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:08:08.893] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:21:0) after 1m0s
[20:09:08.917] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:21:0)
[20:09:08.917] Timer tick, asking Buildbucket for the build status
[20:09:09.019] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:09:09.019] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:22:0) after 1m0s
[20:10:09.068] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:22:0)
[20:10:09.068] Timer tick, asking Buildbucket for the build status
[20:10:09.149] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:10:09.149] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:23:0) after 1m0s
[20:11:09.180] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:23:0)
[20:11:09.180] Timer tick, asking Buildbucket for the build status
[20:11:09.265] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:11:09.266] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:24:0) after 1m0s
[20:12:09.334] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:24:0)
[20:12:09.334] Timer tick, asking Buildbucket for the build status
[20:12:09.412] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:12:09.412] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:25:0) after 1m0s
[20:13:09.486] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:25:0)
[20:13:09.486] Timer tick, asking Buildbucket for the build status
[20:13:09.558] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:13:09.558] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:26:0) after 1m0s
[20:14:09.678] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:26:0)
[20:14:09.678] Timer tick, asking Buildbucket for the build status
[20:14:09.779] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:14:09.779] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:27:0) after 1m0s
[20:15:09.874] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:27:0)
[20:15:09.874] Timer tick, asking Buildbucket for the build status
[20:15:09.950] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:15:09.950] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:28:0) after 1m0s
[20:16:09.994] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:28:0)
[20:16:09.994] Timer tick, asking Buildbucket for the build status
[20:16:10.054] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:16:10.054] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:29:0) after 1m0s
[20:17:10.147] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:29:0)
[20:17:10.147] Timer tick, asking Buildbucket for the build status
[20:17:10.299] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:17:10.299] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:30:0) after 1m0s
[20:18:10.325] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:30:0)
[20:18:10.325] Timer tick, asking Buildbucket for the build status
[20:18:10.488] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:18:10.488] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:31:0) after 1m0s
[20:19:10.518] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:31:0)
[20:19:10.518] Timer tick, asking Buildbucket for the build status
[20:19:10.598] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:19:10.598] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:32:0) after 1m0s
[20:20:10.640] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:32:0)
[20:20:10.640] Timer tick, asking Buildbucket for the build status
[20:20:10.723] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:20:10.723] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:33:0) after 1m0s
[20:21:10.775] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:33:0)
[20:21:10.775] Timer tick, asking Buildbucket for the build status
[20:21:10.841] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:21:10.841] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:34:0) after 1m0s
[20:22:10.859] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:34:0)
[20:22:10.859] Timer tick, asking Buildbucket for the build status
[20:22:10.933] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:22:10.933] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:35:0) after 1m0s
[20:23:10.953] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:35:0)
[20:23:10.953] Timer tick, asking Buildbucket for the build status
[20:23:11.114] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:23:11.114] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:36:0) after 1m0s
[20:24:11.326] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:36:0)
[20:24:11.326] Timer tick, asking Buildbucket for the build status
[20:24:11.412] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:24:11.412] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:37:0) after 1m0s
[20:25:11.431] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:37:0)
[20:25:11.431] Timer tick, asking Buildbucket for the build status
[20:25:11.520] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:25:11.520] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:38:0) after 1m0s
[20:26:11.546] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:38:0)
[20:26:11.546] Timer tick, asking Buildbucket for the build status
[20:26:11.722] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:26:11.722] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:39:0) after 1m0s
[20:27:11.747] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:39:0)
[20:27:11.747] Timer tick, asking Buildbucket for the build status
[20:27:11.828] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:27:11.828] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:40:0) after 1m0s
[20:28:11.877] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:40:0)
[20:28:11.877] Timer tick, asking Buildbucket for the build status
[20:28:11.944] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:28:11.944] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:41:0) after 1m0s
[20:29:11.968] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:41:0)
[20:29:11.968] Timer tick, asking Buildbucket for the build status
[20:29:12.047] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:29:12.047] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:42:0) after 1m0s
[20:30:12.071] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:42:0)
[20:30:12.071] Timer tick, asking Buildbucket for the build status
[20:30:12.274] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:30:12.274] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:43:0) after 1m0s
[20:31:12.315] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:43:0)
[20:31:12.315] Timer tick, asking Buildbucket for the build status
[20:31:12.384] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:31:12.384] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:44:0) after 1m0s
[20:32:12.404] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:44:0)
[20:32:12.404] Timer tick, asking Buildbucket for the build status
[20:32:12.498] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:32:12.498] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:45:0) after 1m0s
[20:33:12.537] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:45:0)
[20:33:12.537] Timer tick, asking Buildbucket for the build status
[20:33:12.605] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:33:12.605] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:46:0) after 1m0s
[20:34:12.652] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:46:0)
[20:34:12.652] Timer tick, asking Buildbucket for the build status
[20:34:12.742] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:34:12.742] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:47:0) after 1m0s
[20:35:12.781] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:47:0)
[20:35:12.781] Timer tick, asking Buildbucket for the build status
[20:35:12.844] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:35:12.844] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:48:0) after 1m0s
[20:36:12.884] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:48:0)
[20:36:12.884] Timer tick, asking Buildbucket for the build status
[20:36:12.961] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:36:12.961] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:49:0) after 1m0s
[20:37:12.991] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:49:0)
[20:37:12.991] Timer tick, asking Buildbucket for the build status
[20:37:13.099] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:37:13.099] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:50:0) after 1m0s
[20:38:13.211] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:50:0)
[20:38:13.211] Timer tick, asking Buildbucket for the build status
[20:38:13.321] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:38:13.321] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:51:0) after 1m0s
[20:39:13.363] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:51:0)
[20:39:13.363] Timer tick, asking Buildbucket for the build status
[20:39:13.425] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:39:13.425] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:52:0) after 1m0s
[20:40:13.469] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:52:0)
[20:40:13.469] Timer tick, asking Buildbucket for the build status
[20:40:13.564] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:40:13.564] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:53:0) after 1m0s
[20:41:13.693] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:53:0)
[20:41:13.693] Timer tick, asking Buildbucket for the build status
[20:41:13.755] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:41:13.755] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:54:0) after 1m0s
[20:42:13.872] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:54:0)
[20:42:13.872] Timer tick, asking Buildbucket for the build status
[20:42:13.938] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:42:13.938] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:55:0) after 1m0s
[20:43:14.004] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:55:0)
[20:43:14.004] Timer tick, asking Buildbucket for the build status
[20:43:14.083] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:43:14.083] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:56:0) after 1m0s
[20:44:14.133] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:56:0)
[20:44:14.133] Timer tick, asking Buildbucket for the build status
[20:44:14.194] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:44:14.194] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:57:0) after 1m0s
[20:45:14.285] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:57:0)
[20:45:14.285] Timer tick, asking Buildbucket for the build status
[20:45:14.345] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:45:14.345] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:58:0) after 1m0s
[20:46:14.470] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:58:0)
[20:46:14.470] Timer tick, asking Buildbucket for the build status
[20:46:14.530] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:46:14.530] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:59:0) after 1m0s
[20:47:14.755] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:59:0)
[20:47:14.755] Timer tick, asking Buildbucket for the build status
[20:47:14.892] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:47:14.892] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:60:0) after 1m0s
[20:48:15.030] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:60:0)
[20:48:15.030] Timer tick, asking Buildbucket for the build status
[20:48:15.100] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:48:15.100] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:61:0) after 1m0s
[20:49:15.190] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:61:0)
[20:49:15.190] Timer tick, asking Buildbucket for the build status
[20:49:15.245] Build 8933839917968087168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:49:15.245] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9099269980140313536:62:0) after 1m0s
[20:50:12.519] Received PubSub notification, asking Buildbucket for the build status
[20:50:12.566] Build 8933839917968087168: status "COMPLETED", result "CANCELED", failure_reason "", cancelation_reason "TIMEOUT"
[20:50:12.566] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "AUTO",
"cancelation_reason": "TIMEOUT",
"completed_ts": "1538427010922199",
"created_by": "user:luci-scheduler-dev@appspot.gserviceaccount.com",
"created_ts": "1538423345556288",
"id": "8933839917968087168",
"parameters_json": "{\"builder_name\": \"infra-continuous-win10-64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra.git\", \"revision\": \"e103a6744e0d6380e29650acf3ee3e7cbaf071a8\"}}",
"project": "infra",
"result": "CANCELED",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {}}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "COMPLETED",
"status_changed_ts": "1538427011655770",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/4564",
"builder:infra-continuous-win10-64",
"buildset:commit/git/e103a6744e0d6380e29650acf3ee3e7cbaf071a8",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/e103a6744e0d6380e29650acf3ee3e7cbaf071a8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9099269980140313536",
"scheduler_job_id:infra/infra-continuous-win10-64",
"swarming_dimension:caches:builder_7785915434d7e14cb476edecfeb7743101007da4f242b5bf463abf3eb5feb198_v2",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:Chrome",
"swarming_hostname:chromium-swarm-dev.appspot.com",
"swarming_tag:allow_milo:1",
"swarming_tag:build_address:luci.infra.ci/infra-continuous-win10-64/4564",
"swarming_tag:buildbucket_bucket:luci.infra.ci",
"swarming_tag:buildbucket_build_id:8933839917968087168",
"swarming_tag:buildbucket_hostname:cr-buildbucket-dev.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:1630ff158d8d4118027817e4d74c356b46464ed9",
"swarming_tag:builder:infra-continuous-win10-64",
"swarming_tag:buildset:commit/git/e103a6744e0d6380e29650acf3ee3e7cbaf071a8",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/e103a6744e0d6380e29650acf3ee3e7cbaf071a8",
"swarming_tag:caches:builder_7785915434d7e14cb476edecfeb7743101007da4f242b5bf463abf3eb5feb198_v2",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://luci-logdog-dev.appspot.com/infra/buildbucket/cr-buildbucket-dev.appspot.com/8933839917968087168/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:Chrome",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:infra_continuous",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_tag:scheduler_invocation_id:9099269980140313536",
"swarming_tag:scheduler_job_id:infra/infra-continuous-win10-64",
"swarming_tag:service_account:adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:prod",
"swarming_tag:swarming.pool.version:419c10a306b2ebebcabc2bb490ca6fce54e43049",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler-dev",
"swarming_task_id:4049fedb89d28410",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1538427011656930",
"url": "https://luci-milo-dev.appspot.com/p/infra/builders/luci.infra.ci/infra-continuous-win10-64/4564",
"utcnow_ts": "1538427012554827"
}
}
[20:50:12.566] Invocation finished in 1h1m8.465324148s with status FAILED