[19:07:42.297] New invocation is queued and will start shortly
[19:07:43.665] Starting the invocation (attempt 1)
[19:07:43.705] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[19:07:43.705] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[19:07:43.705] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9105251982300889040",
"parameters_json": "{\"builder_name\":\"infra-continuous-win10-64\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra.git\",\"revision\":\"8d3835293b80b03690dfadbed10956ff8911e9b6\"}}",
"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:9105251982300889040",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/8d3835293b80b03690dfadbed10956ff8911e9b6",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/8d3835293b80b03690dfadbed10956ff8911e9b6",
"gitiles_ref:refs/heads/master"
]
}
[19:07:44.692] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler-dev@appspot.gserviceaccount.com",
"created_ts": "1532718463806890",
"id": "8939821920054475344",
"lease_expiration_ts": "1532810264457000",
"lease_key": "1625558544",
"parameters_json": "{\"builder_name\": \"infra-continuous-win10-64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra.git\", \"revision\": \"8d3835293b80b03690dfadbed10956ff8911e9b6\"}}",
"project": "infra",
"result_details_json": "null",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1532718464587630",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/3489",
"builder:infra-continuous-win10-64",
"buildset:commit/git/8d3835293b80b03690dfadbed10956ff8911e9b6",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/8d3835293b80b03690dfadbed10956ff8911e9b6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9105251982300889040",
"scheduler_job_id:infra/infra-continuous-win10-64",
"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/3489",
"swarming_tag:buildbucket_bucket:luci.infra.ci",
"swarming_tag:buildbucket_build_id:8939821920054475344",
"swarming_tag:buildbucket_hostname:cr-buildbucket-dev.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:31208890f23bdb2fc5a18d6f2e1d18355d60a3a4",
"swarming_tag:builder:infra-continuous-win10-64",
"swarming_tag:buildset:commit/git/8d3835293b80b03690dfadbed10956ff8911e9b6",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/8d3835293b80b03690dfadbed10956ff8911e9b6",
"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/8939821920054475344/+/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:9105251982300889040",
"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:8e9b8e34de2b5554a54bb4fbf3f0f599dc7e5f33",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler-dev",
"swarming_task_id:3ef5f5451450db10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1532718464587760",
"url": "https://luci-milo-dev.appspot.com/p/infra/builders/luci.infra.ci/infra-continuous-win10-64/3489",
"utcnow_ts": "1532718464679120"
}
}
[19:07:44.692] Task URL: https://luci-milo-dev.appspot.com/p/infra/builders/luci.infra.ci/infra-continuous-win10-64/3489
[19:07:44.692] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:2:0) after 1m0s
[19:08:44.774] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:2:0)
[19:08:44.774] Timer tick, asking Buildbucket for the build status
[19:08:44.886] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:08:44.886] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:3:0) after 1m0s
[19:09:44.986] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:3:0)
[19:09:44.986] Timer tick, asking Buildbucket for the build status
[19:09:45.167] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:09:45.167] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:4:0) after 1m0s
[19:10:45.268] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:4:0)
[19:10:45.268] Timer tick, asking Buildbucket for the build status
[19:10:45.461] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:10:45.461] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:5:0) after 1m0s
[19:11:45.548] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:5:0)
[19:11:45.548] Timer tick, asking Buildbucket for the build status
[19:11:45.753] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:11:45.753] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:6:0) after 1m0s
[19:12:45.786] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:6:0)
[19:12:45.786] Timer tick, asking Buildbucket for the build status
[19:12:45.926] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:12:45.926] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:7:0) after 1m0s
[19:13:46.149] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:7:0)
[19:13:46.149] Timer tick, asking Buildbucket for the build status
[19:13:46.243] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:13:46.243] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:8:0) after 1m0s
[19:14:46.403] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:8:0)
[19:14:46.403] Timer tick, asking Buildbucket for the build status
[19:14:46.616] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:14:46.616] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:9:0) after 1m0s
[19:15:46.709] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:9:0)
[19:15:46.709] Timer tick, asking Buildbucket for the build status
[19:15:46.945] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:15:46.945] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:10:0) after 1m0s
[19:16:47.031] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:10:0)
[19:16:47.031] Timer tick, asking Buildbucket for the build status
[19:16:47.161] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:16:47.161] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:11:0) after 1m0s
[19:17:47.269] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:11:0)
[19:17:47.269] Timer tick, asking Buildbucket for the build status
[19:17:47.389] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:17:47.389] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:12:0) after 1m0s
[19:18:47.478] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:12:0)
[19:18:47.478] Timer tick, asking Buildbucket for the build status
[19:18:47.625] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:18:47.625] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:13:0) after 1m0s
[19:19:47.751] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:13:0)
[19:19:47.751] Timer tick, asking Buildbucket for the build status
[19:19:47.840] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:19:47.840] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:14:0) after 1m0s
[19:20:47.887] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:14:0)
[19:20:47.887] Timer tick, asking Buildbucket for the build status
[19:20:47.955] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:20:47.955] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:15:0) after 1m0s
[19:21:47.998] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:15:0)
[19:21:47.998] Timer tick, asking Buildbucket for the build status
[19:21:48.146] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:21:48.146] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:16:0) after 1m0s
[19:22:48.273] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:16:0)
[19:22:48.273] Timer tick, asking Buildbucket for the build status
[19:22:48.371] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:22:48.371] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:17:0) after 1m0s
[19:23:48.475] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:17:0)
[19:23:48.475] Timer tick, asking Buildbucket for the build status
[19:23:48.596] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:23:48.597] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:18:0) after 1m0s
[19:24:48.767] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:18:0)
[19:24:48.767] Timer tick, asking Buildbucket for the build status
[19:24:48.856] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:24:48.856] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:19:0) after 1m0s
[19:25:48.881] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:19:0)
[19:25:48.881] Timer tick, asking Buildbucket for the build status
[19:25:48.952] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:25:48.952] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:20:0) after 1m0s
[19:26:49.044] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:20:0)
[19:26:49.044] Timer tick, asking Buildbucket for the build status
[19:26:49.208] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:26:49.208] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:21:0) after 1m0s
[19:27:49.287] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:21:0)
[19:27:49.287] Timer tick, asking Buildbucket for the build status
[19:27:49.365] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:27:49.365] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:22:0) after 1m0s
[19:28:49.391] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:22:0)
[19:28:49.391] Timer tick, asking Buildbucket for the build status
[19:28:49.466] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:28:49.466] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:23:0) after 1m0s
[19:29:49.512] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:23:0)
[19:29:49.512] Timer tick, asking Buildbucket for the build status
[19:29:49.650] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:29:49.650] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:24:0) after 1m0s
[19:30:49.767] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:24:0)
[19:30:49.767] Timer tick, asking Buildbucket for the build status
[19:30:49.858] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:30:49.858] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:25:0) after 1m0s
[19:31:49.982] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:25:0)
[19:31:49.982] Timer tick, asking Buildbucket for the build status
[19:31:50.069] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:31:50.069] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:26:0) after 1m0s
[19:32:50.193] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:26:0)
[19:32:50.193] Timer tick, asking Buildbucket for the build status
[19:32:50.257] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:32:50.257] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:27:0) after 1m0s
[19:33:50.363] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:27:0)
[19:33:50.363] Timer tick, asking Buildbucket for the build status
[19:33:50.451] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:33:50.451] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:28:0) after 1m0s
[19:34:50.502] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:28:0)
[19:34:50.502] Timer tick, asking Buildbucket for the build status
[19:34:50.651] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:34:50.651] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:29:0) after 1m0s
[19:35:50.821] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:29:0)
[19:35:50.821] Timer tick, asking Buildbucket for the build status
[19:35:50.920] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:35:50.920] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:30:0) after 1m0s
[19:36:50.940] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:30:0)
[19:36:50.940] Timer tick, asking Buildbucket for the build status
[19:36:51.019] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:36:51.019] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:31:0) after 1m0s
[19:37:51.045] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:31:0)
[19:37:51.045] Timer tick, asking Buildbucket for the build status
[19:37:51.151] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:37:51.151] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:32:0) after 1m0s
[19:38:51.178] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:32:0)
[19:38:51.178] Timer tick, asking Buildbucket for the build status
[19:38:51.261] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:38:51.261] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:33:0) after 1m0s
[19:39:51.284] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:33:0)
[19:39:51.284] Timer tick, asking Buildbucket for the build status
[19:39:51.397] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:39:51.397] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:34:0) after 1m0s
[19:40:51.487] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:34:0)
[19:40:51.487] Timer tick, asking Buildbucket for the build status
[19:40:51.579] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:40:51.579] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:35:0) after 1m0s
[19:41:51.599] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:35:0)
[19:41:51.599] Timer tick, asking Buildbucket for the build status
[19:41:51.745] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:41:51.745] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:36:0) after 1m0s
[19:42:51.766] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:36:0)
[19:42:51.766] Timer tick, asking Buildbucket for the build status
[19:42:52.022] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:42:52.022] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:37:0) after 1m0s
[19:43:52.047] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:37:0)
[19:43:52.047] Timer tick, asking Buildbucket for the build status
[19:43:52.168] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:43:52.168] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:38:0) after 1m0s
[19:44:52.241] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:38:0)
[19:44:52.241] Timer tick, asking Buildbucket for the build status
[19:44:52.365] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:44:52.365] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:39:0) after 1m0s
[19:45:52.384] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:39:0)
[19:45:52.384] Timer tick, asking Buildbucket for the build status
[19:45:52.622] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:45:52.622] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:40:0) after 1m0s
[19:46:52.695] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:40:0)
[19:46:52.695] Timer tick, asking Buildbucket for the build status
[19:46:52.853] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:46:52.853] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:41:0) after 1m0s
[19:47:52.881] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:41:0)
[19:47:52.881] Timer tick, asking Buildbucket for the build status
[19:47:53.016] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:47:53.016] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:42:0) after 1m0s
[19:48:53.047] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:42:0)
[19:48:53.047] Timer tick, asking Buildbucket for the build status
[19:48:53.137] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:48:53.137] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:43:0) after 1m0s
[19:49:53.154] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:43:0)
[19:49:53.154] Timer tick, asking Buildbucket for the build status
[19:49:53.246] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:49:53.246] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:44:0) after 1m0s
[19:50:53.268] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:44:0)
[19:50:53.268] Timer tick, asking Buildbucket for the build status
[19:50:53.405] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:50:53.405] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:45:0) after 1m0s
[19:51:53.512] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:45:0)
[19:51:53.512] Timer tick, asking Buildbucket for the build status
[19:51:53.638] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:51:53.638] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:46:0) after 1m0s
[19:52:53.723] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:46:0)
[19:52:53.723] Timer tick, asking Buildbucket for the build status
[19:52:53.780] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:52:53.780] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:47:0) after 1m0s
[19:53:53.833] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:47:0)
[19:53:53.833] Timer tick, asking Buildbucket for the build status
[19:53:53.966] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:53:53.966] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:48:0) after 1m0s
[19:54:54.166] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:48:0)
[19:54:54.166] Timer tick, asking Buildbucket for the build status
[19:54:54.239] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:54:54.239] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:49:0) after 1m0s
[19:55:54.299] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:49:0)
[19:55:54.299] Timer tick, asking Buildbucket for the build status
[19:55:54.452] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:55:54.452] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:50:0) after 1m0s
[19:56:54.572] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:50:0)
[19:56:54.572] Timer tick, asking Buildbucket for the build status
[19:56:54.653] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:56:54.653] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:51:0) after 1m0s
[19:57:54.779] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:51:0)
[19:57:54.779] Timer tick, asking Buildbucket for the build status
[19:57:54.859] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:57:54.859] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:52:0) after 1m0s
[19:58:54.986] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:52:0)
[19:58:54.986] Timer tick, asking Buildbucket for the build status
[19:58:55.053] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:58:55.053] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:53:0) after 1m0s
[19:59:55.179] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:53:0)
[19:59:55.179] Timer tick, asking Buildbucket for the build status
[19:59:55.255] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:59:55.255] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:54:0) after 1m0s
[20:00:55.297] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:54:0)
[20:00:55.297] Timer tick, asking Buildbucket for the build status
[20:00:55.442] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:00:55.442] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:55:0) after 1m0s
[20:01:55.551] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:55:0)
[20:01:55.551] Timer tick, asking Buildbucket for the build status
[20:01:55.639] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:01:55.639] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:56:0) after 1m0s
[20:02:55.712] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:56:0)
[20:02:55.712] Timer tick, asking Buildbucket for the build status
[20:02:55.847] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:02:55.847] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:57:0) after 1m0s
[20:03:55.973] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:57:0)
[20:03:55.973] Timer tick, asking Buildbucket for the build status
[20:03:56.042] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:03:56.042] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:58:0) after 1m0s
[20:04:56.079] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:58:0)
[20:04:56.079] Timer tick, asking Buildbucket for the build status
[20:04:56.168] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:04:56.168] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:59:0) after 1m0s
[20:05:56.350] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:59:0)
[20:05:56.350] Timer tick, asking Buildbucket for the build status
[20:05:56.467] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:05:56.467] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:60:0) after 1m0s
[20:06:56.543] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:60:0)
[20:06:56.543] Timer tick, asking Buildbucket for the build status
[20:06:56.651] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:06:56.651] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:61:0) after 1m0s
[20:07:56.766] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:61:0)
[20:07:56.766] Timer tick, asking Buildbucket for the build status
[20:07:56.848] Build 8939821920054475344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:07:56.848] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9105251982300889040:62:0) after 1m0s
[20:08:01.759] Received PubSub notification, asking Buildbucket for the build status
[20:08:01.806] Build 8939821920054475344: status "COMPLETED", result "CANCELED", failure_reason "", cancelation_reason "TIMEOUT"
[20:08:01.806] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "AUTO",
"cancelation_reason": "TIMEOUT",
"completed_ts": "1532722080435470",
"created_by": "user:luci-scheduler-dev@appspot.gserviceaccount.com",
"created_ts": "1532718463806890",
"id": "8939821920054475344",
"parameters_json": "{\"builder_name\": \"infra-continuous-win10-64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra.git\", \"revision\": \"8d3835293b80b03690dfadbed10956ff8911e9b6\"}}",
"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": "1532722081239110",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/3489",
"builder:infra-continuous-win10-64",
"buildset:commit/git/8d3835293b80b03690dfadbed10956ff8911e9b6",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/8d3835293b80b03690dfadbed10956ff8911e9b6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9105251982300889040",
"scheduler_job_id:infra/infra-continuous-win10-64",
"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/3489",
"swarming_tag:buildbucket_bucket:luci.infra.ci",
"swarming_tag:buildbucket_build_id:8939821920054475344",
"swarming_tag:buildbucket_hostname:cr-buildbucket-dev.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:31208890f23bdb2fc5a18d6f2e1d18355d60a3a4",
"swarming_tag:builder:infra-continuous-win10-64",
"swarming_tag:buildset:commit/git/8d3835293b80b03690dfadbed10956ff8911e9b6",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/8d3835293b80b03690dfadbed10956ff8911e9b6",
"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/8939821920054475344/+/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:9105251982300889040",
"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:8e9b8e34de2b5554a54bb4fbf3f0f599dc7e5f33",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler-dev",
"swarming_task_id:3ef5f5451450db10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1532722081240390",
"url": "https://luci-milo-dev.appspot.com/p/infra/builders/luci.infra.ci/infra-continuous-win10-64/3489",
"utcnow_ts": "1532722081793330"
}
}
[20:08:01.806] Invocation finished in 1h0m19.525248764s with status FAILED