[18:29:26.138] New invocation is queued and will start shortly
[18:29:27.261] Starting the invocation (attempt 1)
[18:29:27.310] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[18:29:27.310] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[18:29:27.311] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9031236668450327408",
"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\":\"20c37d49fdc1cef07aaa58ce76d599a1551e6761\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@20c37d49fdc1cef07aaa58ce76d599a1551e6761\",\"title\":\"20c37d49fdc1cef07aaa58ce76d599a1551e6761\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/20c37d49fdc1cef07aaa58ce76d599a1551e6761\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"21d7a28f68b88bda95b84a49ee6f23abf06cea6a\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@21d7a28f68b88bda95b84a49ee6f23abf06cea6a\",\"title\":\"21d7a28f68b88bda95b84a49ee6f23abf06cea6a\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/21d7a28f68b88bda95b84a49ee6f23abf06cea6a\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"21d7a28f68b88bda95b84a49ee6f23abf06cea6a\"}}",
"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:9031236668450327408",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/21d7a28f68b88bda95b84a49ee6f23abf06cea6a",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/21d7a28f68b88bda95b84a49ee6f23abf06cea6a",
"gitiles_ref:refs/heads/master"
]
}
[18:29:28.129] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1603304967506172",
"id": "8865806606350628528",
"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\": \"20c37d49fdc1cef07aaa58ce76d599a1551e6761\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@20c37d49fdc1cef07aaa58ce76d599a1551e6761\", \"title\": \"20c37d49fdc1cef07aaa58ce76d599a1551e6761\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/20c37d49fdc1cef07aaa58ce76d599a1551e6761\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"21d7a28f68b88bda95b84a49ee6f23abf06cea6a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@21d7a28f68b88bda95b84a49ee6f23abf06cea6a\", \"title\": \"21d7a28f68b88bda95b84a49ee6f23abf06cea6a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/21d7a28f68b88bda95b84a49ee6f23abf06cea6a\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"21d7a28f68b88bda95b84a49ee6f23abf06cea6a\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603304967984304",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17911",
"builder:infra-continuous-win10-64",
"buildset:commit/git/21d7a28f68b88bda95b84a49ee6f23abf06cea6a",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/21d7a28f68b88bda95b84a49ee6f23abf06cea6a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031236668450327408",
"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/8865806606350628528/+/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": "1603304967984454",
"url": "https://luci-milo-dev.appspot.com/b/8865806606350628528",
"utcnow_ts": "1603304968114715"
}
}
[18:29:28.129] Task URL: https://luci-milo-dev.appspot.com/b/8865806606350628528
[18:29:28.129] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:2:0) after 1m0s
[18:30:28.166] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:2:0)
[18:30:28.166] Timer tick, asking Buildbucket for the build status
[18:30:28.266] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:30:28.266] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:3:0) after 1m0s
[18:31:28.293] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:3:0)
[18:31:28.293] Timer tick, asking Buildbucket for the build status
[18:31:28.422] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:31:28.422] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:4:0) after 1m0s
[18:32:28.481] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:4:0)
[18:32:28.481] Timer tick, asking Buildbucket for the build status
[18:32:28.579] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:32:28.579] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:5:0) after 1m0s
[18:33:28.608] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:5:0)
[18:33:28.608] Timer tick, asking Buildbucket for the build status
[18:33:28.680] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:33:28.680] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:6:0) after 1m0s
[18:34:28.776] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:6:0)
[18:34:28.776] Timer tick, asking Buildbucket for the build status
[18:34:28.875] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:34:28.875] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:7:0) after 1m0s
[18:35:28.983] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:7:0)
[18:35:28.983] Timer tick, asking Buildbucket for the build status
[18:35:29.065] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:35:29.065] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:8:0) after 1m0s
[18:36:29.098] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:8:0)
[18:36:29.098] Timer tick, asking Buildbucket for the build status
[18:36:29.231] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:36:29.231] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:9:0) after 1m0s
[18:37:29.261] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:9:0)
[18:37:29.262] Timer tick, asking Buildbucket for the build status
[18:37:29.349] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:37:29.349] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:10:0) after 1m0s
[18:38:29.375] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:10:0)
[18:38:29.375] Timer tick, asking Buildbucket for the build status
[18:38:30.923] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:38:30.923] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:11:0) after 1m0s
[18:39:30.963] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:11:0)
[18:39:30.963] Timer tick, asking Buildbucket for the build status
[18:39:31.055] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:39:31.055] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:12:0) after 1m0s
[18:40:31.162] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:12:0)
[18:40:31.162] Timer tick, asking Buildbucket for the build status
[18:40:31.264] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:40:31.264] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:13:0) after 1m0s
[18:41:31.363] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:13:0)
[18:41:31.363] Timer tick, asking Buildbucket for the build status
[18:41:31.560] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:41:31.561] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:14:0) after 1m0s
[18:42:31.681] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:14:0)
[18:42:31.681] Timer tick, asking Buildbucket for the build status
[18:42:31.752] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:42:31.752] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:15:0) after 1m0s
[18:43:31.777] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:15:0)
[18:43:31.778] Timer tick, asking Buildbucket for the build status
[18:43:31.950] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:43:31.950] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:16:0) after 1m0s
[18:44:32.062] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:16:0)
[18:44:32.062] Timer tick, asking Buildbucket for the build status
[18:44:32.159] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:44:32.159] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:17:0) after 1m0s
[18:45:32.185] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:17:0)
[18:45:32.185] Timer tick, asking Buildbucket for the build status
[18:45:32.259] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:45:32.259] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:18:0) after 1m0s
[18:46:32.361] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:18:0)
[18:46:32.361] Timer tick, asking Buildbucket for the build status
[18:46:32.526] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:46:32.526] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:19:0) after 1m0s
[18:47:32.579] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:19:0)
[18:47:32.579] Timer tick, asking Buildbucket for the build status
[18:47:32.676] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:47:32.676] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:20:0) after 1m0s
[18:48:32.775] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:20:0)
[18:48:32.775] Timer tick, asking Buildbucket for the build status
[18:48:32.964] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:48:32.964] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:21:0) after 1m0s
[18:49:33.059] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:21:0)
[18:49:33.059] Timer tick, asking Buildbucket for the build status
[18:49:33.160] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:49:33.160] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:22:0) after 1m0s
[18:50:33.281] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:22:0)
[18:50:33.281] Timer tick, asking Buildbucket for the build status
[18:50:33.390] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:50:33.390] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:23:0) after 1m0s
[18:51:33.412] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:23:0)
[18:51:33.412] Timer tick, asking Buildbucket for the build status
[18:51:33.520] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:51:33.520] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:24:0) after 1m0s
[18:52:33.543] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:24:0)
[18:52:33.543] Timer tick, asking Buildbucket for the build status
[18:52:33.635] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:52:33.635] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:25:0) after 1m0s
[18:53:33.662] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:25:0)
[18:53:33.662] Timer tick, asking Buildbucket for the build status
[18:53:33.743] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:53:33.743] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:26:0) after 1m0s
[18:54:33.779] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:26:0)
[18:54:33.779] Timer tick, asking Buildbucket for the build status
[18:54:33.901] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:54:33.901] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:27:0) after 1m0s
[18:55:33.976] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:27:0)
[18:55:33.976] Timer tick, asking Buildbucket for the build status
[18:55:34.057] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:55:34.057] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:28:0) after 1m0s
[18:56:34.090] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:28:0)
[18:56:34.090] Timer tick, asking Buildbucket for the build status
[18:56:34.193] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:56:34.193] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:29:0) after 1m0s
[18:57:34.211] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:29:0)
[18:57:34.211] Timer tick, asking Buildbucket for the build status
[18:57:34.377] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:57:34.377] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:30:0) after 1m0s
[18:58:34.469] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:30:0)
[18:58:34.469] Timer tick, asking Buildbucket for the build status
[18:58:34.567] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:58:34.567] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:31:0) after 1m0s
[18:59:34.671] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:31:0)
[18:59:34.671] Timer tick, asking Buildbucket for the build status
[18:59:34.825] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:59:34.825] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:32:0) after 1m0s
[19:00:34.881] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:32:0)
[19:00:34.881] Timer tick, asking Buildbucket for the build status
[19:00:34.978] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:00:34.978] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:33:0) after 1m0s
[19:01:35.085] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:33:0)
[19:01:35.085] Timer tick, asking Buildbucket for the build status
[19:01:35.192] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:01:35.192] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:34:0) after 1m0s
[19:02:35.265] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:34:0)
[19:02:35.265] Timer tick, asking Buildbucket for the build status
[19:02:35.412] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:02:35.412] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:35:0) after 1m0s
[19:03:35.468] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:35:0)
[19:03:35.468] Timer tick, asking Buildbucket for the build status
[19:03:35.565] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:03:35.565] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:36:0) after 1m0s
[19:04:35.591] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:36:0)
[19:04:35.591] Timer tick, asking Buildbucket for the build status
[19:04:35.684] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:04:35.685] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:37:0) after 1m0s
[19:05:35.775] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:37:0)
[19:05:35.775] Timer tick, asking Buildbucket for the build status
[19:05:35.901] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:05:35.901] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:38:0) after 1m0s
[19:06:35.935] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:38:0)
[19:06:35.935] Timer tick, asking Buildbucket for the build status
[19:06:36.024] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:06:36.024] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:39:0) after 1m0s
[19:07:36.057] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:39:0)
[19:07:36.057] Timer tick, asking Buildbucket for the build status
[19:07:36.194] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:07:36.194] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:40:0) after 1m0s
[19:08:36.321] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:40:0)
[19:08:36.321] Timer tick, asking Buildbucket for the build status
[19:08:36.544] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:08:36.544] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:41:0) after 1m0s
[19:09:36.710] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:41:0)
[19:09:36.710] Timer tick, asking Buildbucket for the build status
[19:09:36.817] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:09:36.817] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:42:0) after 1m0s
[19:10:36.873] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:42:0)
[19:10:36.873] Timer tick, asking Buildbucket for the build status
[19:10:36.978] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:10:36.978] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:43:0) after 1m0s
[19:11:37.067] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:43:0)
[19:11:37.067] Timer tick, asking Buildbucket for the build status
[19:11:37.343] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:11:37.343] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:44:0) after 1m0s
[19:12:37.374] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:44:0)
[19:12:37.374] Timer tick, asking Buildbucket for the build status
[19:12:37.495] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:12:37.495] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:45:0) after 1m0s
[19:13:37.522] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:45:0)
[19:13:37.522] Timer tick, asking Buildbucket for the build status
[19:13:37.624] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:13:37.624] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:46:0) after 1m0s
[19:14:37.735] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:46:0)
[19:14:37.735] Timer tick, asking Buildbucket for the build status
[19:14:37.885] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:14:37.885] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:47:0) after 1m0s
[19:15:37.980] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:47:0)
[19:15:37.980] Timer tick, asking Buildbucket for the build status
[19:15:38.109] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:15:38.109] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:48:0) after 1m0s
[19:16:38.161] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:48:0)
[19:16:38.161] Timer tick, asking Buildbucket for the build status
[19:16:38.285] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:16:38.285] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:49:0) after 1m0s
[19:17:38.312] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:49:0)
[19:17:38.312] Timer tick, asking Buildbucket for the build status
[19:17:38.454] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:17:38.454] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:50:0) after 1m0s
[19:18:38.484] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:50:0)
[19:18:38.484] Timer tick, asking Buildbucket for the build status
[19:18:38.691] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:18:38.691] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:51:0) after 1m0s
[19:19:38.865] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:51:0)
[19:19:38.865] Timer tick, asking Buildbucket for the build status
[19:19:39.028] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:19:39.028] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:52:0) after 1m0s
[19:20:39.054] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:52:0)
[19:20:39.054] Timer tick, asking Buildbucket for the build status
[19:20:39.156] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:20:39.156] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:53:0) after 1m0s
[19:21:39.181] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:53:0)
[19:21:39.181] Timer tick, asking Buildbucket for the build status
[19:21:39.296] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:21:39.296] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:54:0) after 1m0s
[19:22:39.326] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:54:0)
[19:22:39.326] Timer tick, asking Buildbucket for the build status
[19:22:39.564] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:22:39.564] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:55:0) after 1m0s
[19:23:39.692] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:55:0)
[19:23:39.692] Timer tick, asking Buildbucket for the build status
[19:23:39.787] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:23:39.787] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:56:0) after 1m0s
[19:24:39.819] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:56:0)
[19:24:39.819] Timer tick, asking Buildbucket for the build status
[19:24:40.053] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:24:40.053] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:57:0) after 1m0s
[19:25:40.181] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:57:0)
[19:25:40.181] Timer tick, asking Buildbucket for the build status
[19:25:40.381] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:25:40.381] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:58:0) after 1m0s
[19:26:40.505] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:58:0)
[19:26:40.505] Timer tick, asking Buildbucket for the build status
[19:26:40.636] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:26:40.636] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:59:0) after 1m0s
[19:27:40.769] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:59:0)
[19:27:40.769] Timer tick, asking Buildbucket for the build status
[19:27:40.937] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:27:40.937] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:60:0) after 1m0s
[19:28:40.961] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:60:0)
[19:28:40.961] Timer tick, asking Buildbucket for the build status
[19:28:41.054] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:28:41.054] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:61:0) after 1m0s
[19:29:41.081] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:61:0)
[19:29:41.081] Timer tick, asking Buildbucket for the build status
[19:29:41.160] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:29:41.160] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:62:0) after 1m0s
[19:30:41.268] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:62:0)
[19:30:41.268] Timer tick, asking Buildbucket for the build status
[19:30:41.387] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:30:41.387] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:63:0) after 1m0s
[19:31:41.481] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:63:0)
[19:31:41.481] Timer tick, asking Buildbucket for the build status
[19:31:41.616] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:31:41.616] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:64:0) after 1m0s
[19:32:41.640] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:64:0)
[19:32:41.640] Timer tick, asking Buildbucket for the build status
[19:32:41.744] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:32:41.744] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:65:0) after 1m0s
[19:33:41.768] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:65:0)
[19:33:41.768] Timer tick, asking Buildbucket for the build status
[19:33:41.857] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:33:41.857] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:66:0) after 1m0s
[19:34:41.881] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:66:0)
[19:34:41.882] Timer tick, asking Buildbucket for the build status
[19:34:42.004] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:34:42.004] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:67:0) after 1m0s
[19:35:42.062] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:67:0)
[19:35:42.062] Timer tick, asking Buildbucket for the build status
[19:35:42.147] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:35:42.147] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:68:0) after 1m0s
[19:36:42.186] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:68:0)
[19:36:42.186] Timer tick, asking Buildbucket for the build status
[19:36:42.315] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:36:42.315] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:69:0) after 1m0s
[19:37:42.438] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:69:0)
[19:37:42.438] Timer tick, asking Buildbucket for the build status
[19:37:42.551] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:37:42.551] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:70:0) after 1m0s
[19:38:42.685] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:70:0)
[19:38:42.685] Timer tick, asking Buildbucket for the build status
[19:38:42.769] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:38:42.769] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:71:0) after 1m0s
[19:39:42.796] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:71:0)
[19:39:42.796] Timer tick, asking Buildbucket for the build status
[19:39:42.875] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:39:42.875] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:72:0) after 1m0s
[19:40:42.912] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:72:0)
[19:40:42.912] Timer tick, asking Buildbucket for the build status
[19:40:43.026] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:40:43.026] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:73:0) after 1m0s
[19:41:43.095] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:73:0)
[19:41:43.095] Timer tick, asking Buildbucket for the build status
[19:41:43.211] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:41:43.211] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:74:0) after 1m0s
[19:42:43.283] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:74:0)
[19:42:43.283] Timer tick, asking Buildbucket for the build status
[19:42:43.372] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:42:43.372] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:75:0) after 1m0s
[19:43:43.477] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:75:0)
[19:43:43.477] Timer tick, asking Buildbucket for the build status
[19:43:43.604] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:43:43.604] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:76:0) after 1m0s
[19:44:43.630] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:76:0)
[19:44:43.630] Timer tick, asking Buildbucket for the build status
[19:44:43.734] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:44:43.734] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:77:0) after 1m0s
[19:45:43.780] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:77:0)
[19:45:43.780] Timer tick, asking Buildbucket for the build status
[19:45:43.881] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:45:43.881] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:78:0) after 1m0s
[19:46:43.980] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:78:0)
[19:46:43.980] Timer tick, asking Buildbucket for the build status
[19:46:44.133] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:46:44.133] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:79:0) after 1m0s
[19:47:44.185] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:79:0)
[19:47:44.185] Timer tick, asking Buildbucket for the build status
[19:47:44.271] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:47:44.271] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:80:0) after 1m0s
[19:48:44.303] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:80:0)
[19:48:44.303] Timer tick, asking Buildbucket for the build status
[19:48:44.401] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:48:44.401] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:81:0) after 1m0s
[19:49:44.496] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:81:0)
[19:49:44.496] Timer tick, asking Buildbucket for the build status
[19:49:44.758] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:49:44.758] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:82:0) after 1m0s
[19:50:44.812] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:82:0)
[19:50:44.812] Timer tick, asking Buildbucket for the build status
[19:50:44.962] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:50:44.962] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:83:0) after 1m0s
[19:51:45.082] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:83:0)
[19:51:45.082] Timer tick, asking Buildbucket for the build status
[19:51:45.191] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:51:45.191] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:84:0) after 1m0s
[19:52:45.279] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:84:0)
[19:52:45.279] Timer tick, asking Buildbucket for the build status
[19:52:45.377] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:52:45.377] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:85:0) after 1m0s
[19:53:45.406] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:85:0)
[19:53:45.406] Timer tick, asking Buildbucket for the build status
[19:53:45.531] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:53:45.531] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:86:0) after 1m0s
[19:54:45.583] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:86:0)
[19:54:45.583] Timer tick, asking Buildbucket for the build status
[19:54:45.702] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:54:45.702] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:87:0) after 1m0s
[19:55:45.756] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:87:0)
[19:55:45.756] Timer tick, asking Buildbucket for the build status
[19:55:45.867] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:55:45.867] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:88:0) after 1m0s
[19:56:45.982] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:88:0)
[19:56:45.982] Timer tick, asking Buildbucket for the build status
[19:56:46.095] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:56:46.095] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:89:0) after 1m0s
[19:57:46.133] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:89:0)
[19:57:46.133] Timer tick, asking Buildbucket for the build status
[19:57:46.240] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:57:46.240] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:90:0) after 1m0s
[19:58:46.271] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:90:0)
[19:58:46.271] Timer tick, asking Buildbucket for the build status
[19:58:46.377] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:58:46.377] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:91:0) after 1m0s
[19:59:46.478] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:91:0)
[19:59:46.478] Timer tick, asking Buildbucket for the build status
[19:59:46.566] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:59:46.566] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:92:0) after 1m0s
[20:00:46.592] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:92:0)
[20:00:46.592] Timer tick, asking Buildbucket for the build status
[20:00:46.682] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:00:46.683] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:93:0) after 1m0s
[20:01:46.761] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:93:0)
[20:01:46.761] Timer tick, asking Buildbucket for the build status
[20:01:46.866] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:01:46.866] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:94:0) after 1m0s
[20:02:46.981] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:94:0)
[20:02:46.981] Timer tick, asking Buildbucket for the build status
[20:02:47.078] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:02:47.078] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:95:0) after 1m0s
[20:03:47.108] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:95:0)
[20:03:47.108] Timer tick, asking Buildbucket for the build status
[20:03:47.237] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:03:47.237] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:96:0) after 1m0s
[20:04:47.273] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:96:0)
[20:04:47.273] Timer tick, asking Buildbucket for the build status
[20:04:47.547] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:04:47.547] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:97:0) after 1m0s
[20:05:47.578] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:97:0)
[20:05:47.578] Timer tick, asking Buildbucket for the build status
[20:05:47.681] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:05:47.681] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:98:0) after 1m0s
[20:06:47.720] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:98:0)
[20:06:47.720] Timer tick, asking Buildbucket for the build status
[20:06:47.824] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:06:47.824] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:99:0) after 1m0s
[20:07:47.880] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:99:0)
[20:07:47.880] Timer tick, asking Buildbucket for the build status
[20:07:48.043] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:07:48.043] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:100:0) after 1m0s
[20:08:48.075] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:100:0)
[20:08:48.075] Timer tick, asking Buildbucket for the build status
[20:08:48.176] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:08:48.176] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:101:0) after 1m0s
[20:09:48.206] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:101:0)
[20:09:48.206] Timer tick, asking Buildbucket for the build status
[20:09:48.321] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:09:48.321] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:102:0) after 1m0s
[20:10:48.354] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:102:0)
[20:10:48.354] Timer tick, asking Buildbucket for the build status
[20:10:48.443] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:10:48.443] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:103:0) after 1m0s
[20:11:48.472] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:103:0)
[20:11:48.472] Timer tick, asking Buildbucket for the build status
[20:11:48.582] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:11:48.582] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:104:0) after 1m0s
[20:12:48.682] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:104:0)
[20:12:48.682] Timer tick, asking Buildbucket for the build status
[20:12:48.797] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:12:48.797] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:105:0) after 1m0s
[20:13:48.884] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:105:0)
[20:13:48.884] Timer tick, asking Buildbucket for the build status
[20:13:49.002] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:13:49.002] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:106:0) after 1m0s
[20:14:49.045] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:106:0)
[20:14:49.045] Timer tick, asking Buildbucket for the build status
[20:14:49.158] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:14:49.158] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:107:0) after 1m0s
[20:15:49.299] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:107:0)
[20:15:49.299] Timer tick, asking Buildbucket for the build status
[20:15:49.430] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:15:49.430] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:108:0) after 1m0s
[20:16:49.479] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:108:0)
[20:16:49.479] Timer tick, asking Buildbucket for the build status
[20:16:49.614] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:16:49.614] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:109:0) after 1m0s
[20:17:49.689] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:109:0)
[20:17:49.689] Timer tick, asking Buildbucket for the build status
[20:17:49.789] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:17:49.789] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:110:0) after 1m0s
[20:18:49.814] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:110:0)
[20:18:49.814] Timer tick, asking Buildbucket for the build status
[20:18:49.919] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:18:49.919] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:111:0) after 1m0s
[20:19:49.955] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:111:0)
[20:19:49.955] Timer tick, asking Buildbucket for the build status
[20:19:50.173] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:19:50.173] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:112:0) after 1m0s
[20:20:50.276] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:112:0)
[20:20:50.276] Timer tick, asking Buildbucket for the build status
[20:20:50.469] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:20:50.469] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:113:0) after 1m0s
[20:21:50.561] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:113:0)
[20:21:50.561] Timer tick, asking Buildbucket for the build status
[20:21:50.737] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:21:50.737] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:114:0) after 1m0s
[20:22:50.829] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:114:0)
[20:22:50.829] Timer tick, asking Buildbucket for the build status
[20:22:50.937] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:22:50.937] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:115:0) after 1m0s
[20:23:50.979] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:115:0)
[20:23:50.979] Timer tick, asking Buildbucket for the build status
[20:23:51.113] Build 8865806606350628528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:23:51.113] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:116:0) after 1m0s
[20:23:58.299] Received PubSub notification, asking Buildbucket for the build status
[20:23:58.382] Build 8865806606350628528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:51.144] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:116:0)
[20:24:51.144] Timer tick, asking Buildbucket for the build status
[20:24:51.250] Build 8865806606350628528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:51.250] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:118:0) after 1m0s
[20:25:51.302] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:118:0)
[20:25:51.302] Timer tick, asking Buildbucket for the build status
[20:25:51.387] Build 8865806606350628528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:51.387] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:119:0) after 1m0s
[20:26:51.578] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:119:0)
[20:26:51.578] Timer tick, asking Buildbucket for the build status
[20:26:51.702] Build 8865806606350628528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:51.702] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:120:0) after 1m0s
[20:27:51.761] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:120:0)
[20:27:51.761] Timer tick, asking Buildbucket for the build status
[20:27:51.846] Build 8865806606350628528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:51.846] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:121:0) after 1m0s
[20:28:51.891] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:121:0)
[20:28:51.891] Timer tick, asking Buildbucket for the build status
[20:28:51.964] Build 8865806606350628528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:51.964] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:122:0) after 1m0s
[20:29:52.078] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:122:0)
[20:29:52.078] Timer tick, asking Buildbucket for the build status
[20:29:52.201] Build 8865806606350628528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:52.201] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:123:0) after 1m0s
[20:30:52.232] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:123:0)
[20:30:52.232] Timer tick, asking Buildbucket for the build status
[20:30:52.341] Build 8865806606350628528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:52.341] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:124:0) after 1m0s
[20:31:52.371] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:124:0)
[20:31:52.371] Timer tick, asking Buildbucket for the build status
[20:31:52.458] Build 8865806606350628528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:52.458] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:125:0) after 1m0s
[20:32:52.573] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:125:0)
[20:32:52.573] Timer tick, asking Buildbucket for the build status
[20:32:52.670] Build 8865806606350628528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:52.670] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9031236668450327408:126:0) after 1m0s
[20:32:56.861] Received PubSub notification, asking Buildbucket for the build status
[20:32:56.956] Build 8865806606350628528: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:32:56.956] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1603312373180929",
"created_by": "project:infra",
"created_ts": "1603304967506172",
"id": "8865806606350628528",
"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\": \"20c37d49fdc1cef07aaa58ce76d599a1551e6761\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@20c37d49fdc1cef07aaa58ce76d599a1551e6761\", \"title\": \"20c37d49fdc1cef07aaa58ce76d599a1551e6761\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/20c37d49fdc1cef07aaa58ce76d599a1551e6761\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"21d7a28f68b88bda95b84a49ee6f23abf06cea6a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@21d7a28f68b88bda95b84a49ee6f23abf06cea6a\", \"title\": \"21d7a28f68b88bda95b84a49ee6f23abf06cea6a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/21d7a28f68b88bda95b84a49ee6f23abf06cea6a\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"21d7a28f68b88bda95b84a49ee6f23abf06cea6a\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"21d7a28f68b88bda95b84a49ee6f23abf06cea6a\", \"got_revision_cp\": \"refs/heads/master@{#35918}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_044445a3bc7a4bfe795493d7956aa02cd526172de1fa7300f69fe152e82ea631_v2\", \"builder_82ed111fed7ea8169e482f00b5f3e5338a65daa41208585de67cb0a736b1d538_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"git\", \"goma\", \"infra_gclient_with_go\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-rzgy\"], \"image\": [\"chrome-win10-1703-20101600-6f576df2987\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5611-dd39ade\"], \"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": "1603311835402367",
"status": "COMPLETED",
"status_changed_ts": "1603312373509875",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17911",
"builder:infra-continuous-win10-64",
"buildset:commit/git/21d7a28f68b88bda95b84a49ee6f23abf06cea6a",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/21d7a28f68b88bda95b84a49ee6f23abf06cea6a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031236668450327408",
"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/8865806606350628528/+/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:4f653dd831bc5d10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1603312373532118",
"url": "https://luci-milo-dev.appspot.com/b/8865806606350628528",
"utcnow_ts": "1603312376948904"
}
}
[20:32:56.956] Invocation finished in 2h3m30.836300737s with status SUCCEEDED