[22:14:20.212] New invocation is queued and will start shortly
[22:14:21.297] Starting the invocation (attempt 1)
[22:14:21.339] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[22:14:21.339] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[22:14:21.339] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9019082525387170928",
"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\":\"f314e6e2cdf2b4e33d8c9eabde2f949af4378459\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f314e6e2cdf2b4e33d8c9eabde2f949af4378459\",\"title\":\"f314e6e2cdf2b4e33d8c9eabde2f949af4378459\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/f314e6e2cdf2b4e33d8c9eabde2f949af4378459\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"b4d7e3699f917ceb48567e287c9d3dc6805013db\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b4d7e3699f917ceb48567e287c9d3dc6805013db\",\"title\":\"b4d7e3699f917ceb48567e287c9d3dc6805013db\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/b4d7e3699f917ceb48567e287c9d3dc6805013db\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"0c8f6257b8202d4a9b890f33585e83bbdb59bab3\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0c8f6257b8202d4a9b890f33585e83bbdb59bab3\",\"title\":\"0c8f6257b8202d4a9b890f33585e83bbdb59bab3\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/0c8f6257b8202d4a9b890f33585e83bbdb59bab3\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"629b064811b082c4c9f185c3999c7d5d2f9e0d8a\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@629b064811b082c4c9f185c3999c7d5d2f9e0d8a\",\"title\":\"629b064811b082c4c9f185c3999c7d5d2f9e0d8a\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/629b064811b082c4c9f185c3999c7d5d2f9e0d8a\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"629b064811b082c4c9f185c3999c7d5d2f9e0d8a\"}}",
"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:9019082525387170928",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/629b064811b082c4c9f185c3999c7d5d2f9e0d8a",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/629b064811b082c4c9f185c3999c7d5d2f9e0d8a",
"gitiles_ref:refs/heads/master"
]
}
[22:14:22.146] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:infra",
"created_ts": "1614896061452497",
"id": "8853652463424712512",
"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\": \"f314e6e2cdf2b4e33d8c9eabde2f949af4378459\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f314e6e2cdf2b4e33d8c9eabde2f949af4378459\", \"title\": \"f314e6e2cdf2b4e33d8c9eabde2f949af4378459\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/f314e6e2cdf2b4e33d8c9eabde2f949af4378459\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b4d7e3699f917ceb48567e287c9d3dc6805013db\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b4d7e3699f917ceb48567e287c9d3dc6805013db\", \"title\": \"b4d7e3699f917ceb48567e287c9d3dc6805013db\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b4d7e3699f917ceb48567e287c9d3dc6805013db\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0c8f6257b8202d4a9b890f33585e83bbdb59bab3\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0c8f6257b8202d4a9b890f33585e83bbdb59bab3\", \"title\": \"0c8f6257b8202d4a9b890f33585e83bbdb59bab3\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0c8f6257b8202d4a9b890f33585e83bbdb59bab3\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"629b064811b082c4c9f185c3999c7d5d2f9e0d8a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@629b064811b082c4c9f185c3999c7d5d2f9e0d8a\", \"title\": \"629b064811b082c4c9f185c3999c7d5d2f9e0d8a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/629b064811b082c4c9f185c3999c7d5d2f9e0d8a\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"629b064811b082c4c9f185c3999c7d5d2f9e0d8a\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614896061896626",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18783",
"builder:infra-continuous-win10-64",
"buildset:commit/git/629b064811b082c4c9f185c3999c7d5d2f9e0d8a",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/629b064811b082c4c9f185c3999c7d5d2f9e0d8a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019082525387170928",
"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/8853652463424712512/+/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": "1614896061896732",
"url": "https://luci-milo-dev.appspot.com/b/8853652463424712512",
"utcnow_ts": "1614896062052650"
}
}
[22:14:22.146] Task URL: https://luci-milo-dev.appspot.com/b/8853652463424712512
[22:14:22.146] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:2:0) after 1m0s
[22:15:22.180] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:2:0)
[22:15:22.180] Timer tick, asking Buildbucket for the build status
[22:15:22.342] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:22.342] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:3:0) after 1m0s
[22:16:22.378] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:3:0)
[22:16:22.378] Timer tick, asking Buildbucket for the build status
[22:16:22.455] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:22.455] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:4:0) after 1m0s
[22:17:22.479] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:4:0)
[22:17:22.479] Timer tick, asking Buildbucket for the build status
[22:17:22.563] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:22.563] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:5:0) after 1m0s
[22:18:22.650] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:5:0)
[22:18:22.650] Timer tick, asking Buildbucket for the build status
[22:18:22.762] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:22.762] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:6:0) after 1m0s
[22:19:22.789] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:6:0)
[22:19:22.789] Timer tick, asking Buildbucket for the build status
[22:19:22.876] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:22.876] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:7:0) after 1m0s
[22:20:22.978] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:7:0)
[22:20:22.978] Timer tick, asking Buildbucket for the build status
[22:20:23.136] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:23.136] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:8:0) after 1m0s
[22:21:23.157] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:8:0)
[22:21:23.157] Timer tick, asking Buildbucket for the build status
[22:21:23.235] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:23.235] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:9:0) after 1m0s
[22:22:23.261] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:9:0)
[22:22:23.261] Timer tick, asking Buildbucket for the build status
[22:22:23.369] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:23.369] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:10:0) after 1m0s
[22:23:23.391] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:10:0)
[22:23:23.391] Timer tick, asking Buildbucket for the build status
[22:23:23.485] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:23.485] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:11:0) after 1m0s
[22:24:23.513] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:11:0)
[22:24:23.513] Timer tick, asking Buildbucket for the build status
[22:24:23.593] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:23.593] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:12:0) after 1m0s
[22:25:23.689] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:12:0)
[22:25:23.689] Timer tick, asking Buildbucket for the build status
[22:25:23.790] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:23.790] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:13:0) after 1m0s
[22:26:23.811] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:13:0)
[22:26:23.811] Timer tick, asking Buildbucket for the build status
[22:26:23.888] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:23.888] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:14:0) after 1m0s
[22:27:23.958] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:14:0)
[22:27:23.958] Timer tick, asking Buildbucket for the build status
[22:27:24.047] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:24.047] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:15:0) after 1m0s
[22:28:24.065] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:15:0)
[22:28:24.065] Timer tick, asking Buildbucket for the build status
[22:28:24.142] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:24.142] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:16:0) after 1m0s
[22:29:24.267] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:16:0)
[22:29:24.267] Timer tick, asking Buildbucket for the build status
[22:29:24.365] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:24.365] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:17:0) after 1m0s
[22:30:24.512] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:17:0)
[22:30:24.512] Timer tick, asking Buildbucket for the build status
[22:30:24.607] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:24.607] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:18:0) after 1m0s
[22:31:24.665] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:18:0)
[22:31:24.665] Timer tick, asking Buildbucket for the build status
[22:31:24.829] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:24.829] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:19:0) after 1m0s
[22:32:24.874] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:19:0)
[22:32:24.874] Timer tick, asking Buildbucket for the build status
[22:32:24.967] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:24.967] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:20:0) after 1m0s
[22:33:25.003] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:20:0)
[22:33:25.003] Timer tick, asking Buildbucket for the build status
[22:33:25.097] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:25.097] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:21:0) after 1m0s
[22:34:25.168] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:21:0)
[22:34:25.168] Timer tick, asking Buildbucket for the build status
[22:34:25.257] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:25.257] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:22:0) after 1m0s
[22:35:25.289] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:22:0)
[22:35:25.289] Timer tick, asking Buildbucket for the build status
[22:35:25.380] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:25.380] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:23:0) after 1m0s
[22:36:25.493] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:23:0)
[22:36:25.493] Timer tick, asking Buildbucket for the build status
[22:36:25.629] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:25.629] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:24:0) after 1m0s
[22:37:25.666] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:24:0)
[22:37:25.666] Timer tick, asking Buildbucket for the build status
[22:37:25.757] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:25.757] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:25:0) after 1m0s
[22:38:25.796] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:25:0)
[22:38:25.796] Timer tick, asking Buildbucket for the build status
[22:38:25.952] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:25.952] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:26:0) after 1m0s
[22:39:25.980] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:26:0)
[22:39:25.980] Timer tick, asking Buildbucket for the build status
[22:39:26.076] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:26.076] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:27:0) after 1m0s
[22:40:26.165] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:27:0)
[22:40:26.165] Timer tick, asking Buildbucket for the build status
[22:40:26.241] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:26.241] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:28:0) after 1m0s
[22:41:26.263] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:28:0)
[22:41:26.263] Timer tick, asking Buildbucket for the build status
[22:41:26.342] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:26.342] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:29:0) after 1m0s
[22:42:26.366] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:29:0)
[22:42:26.366] Timer tick, asking Buildbucket for the build status
[22:42:26.450] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:26.450] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:30:0) after 1m0s
[22:43:26.483] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:30:0)
[22:43:26.483] Timer tick, asking Buildbucket for the build status
[22:43:26.591] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:26.591] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:31:0) after 1m0s
[22:44:26.653] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:31:0)
[22:44:26.653] Timer tick, asking Buildbucket for the build status
[22:44:26.733] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:26.733] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:32:0) after 1m0s
[22:45:26.754] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:32:0)
[22:45:26.754] Timer tick, asking Buildbucket for the build status
[22:45:26.835] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:26.835] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:33:0) after 1m0s
[22:46:26.890] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:33:0)
[22:46:26.890] Timer tick, asking Buildbucket for the build status
[22:46:27.045] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:27.045] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:34:0) after 1m0s
[22:47:27.072] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:34:0)
[22:47:27.072] Timer tick, asking Buildbucket for the build status
[22:47:27.176] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:27.176] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:35:0) after 1m0s
[22:48:27.296] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:35:0)
[22:48:27.296] Timer tick, asking Buildbucket for the build status
[22:48:27.403] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:27.403] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:36:0) after 1m0s
[22:49:27.426] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:36:0)
[22:49:27.426] Timer tick, asking Buildbucket for the build status
[22:49:27.513] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:27.513] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:37:0) after 1m0s
[22:50:27.538] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:37:0)
[22:50:27.538] Timer tick, asking Buildbucket for the build status
[22:50:27.638] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:27.638] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:38:0) after 1m0s
[22:51:27.660] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:38:0)
[22:51:27.660] Timer tick, asking Buildbucket for the build status
[22:51:27.752] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:27.753] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:39:0) after 1m0s
[22:52:27.801] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:39:0)
[22:52:27.801] Timer tick, asking Buildbucket for the build status
[22:52:27.972] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:27.972] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:40:0) after 1m0s
[22:53:28.052] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:40:0)
[22:53:28.052] Timer tick, asking Buildbucket for the build status
[22:53:28.136] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:28.136] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:41:0) after 1m0s
[22:54:28.180] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:41:0)
[22:54:28.180] Timer tick, asking Buildbucket for the build status
[22:54:28.258] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:28.258] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:42:0) after 1m0s
[22:55:28.361] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:42:0)
[22:55:28.361] Timer tick, asking Buildbucket for the build status
[22:55:28.443] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:28.443] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:43:0) after 1m0s
[22:56:28.484] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:43:0)
[22:56:28.484] Timer tick, asking Buildbucket for the build status
[22:56:28.568] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:28.568] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:44:0) after 1m0s
[22:57:28.698] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:44:0)
[22:57:28.698] Timer tick, asking Buildbucket for the build status
[22:57:28.789] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:28.790] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:45:0) after 1m0s
[22:58:28.889] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:45:0)
[22:58:28.889] Timer tick, asking Buildbucket for the build status
[22:58:28.979] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:28.979] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:46:0) after 1m0s
[22:59:29.049] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:46:0)
[22:59:29.049] Timer tick, asking Buildbucket for the build status
[22:59:29.128] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:29.128] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:47:0) after 1m0s
[23:00:29.154] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:47:0)
[23:00:29.154] Timer tick, asking Buildbucket for the build status
[23:00:29.240] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:29.240] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:48:0) after 1m0s
[23:01:29.267] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:48:0)
[23:01:29.267] Timer tick, asking Buildbucket for the build status
[23:01:29.343] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:29.343] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:49:0) after 1m0s
[23:02:29.368] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:49:0)
[23:02:29.368] Timer tick, asking Buildbucket for the build status
[23:02:29.461] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:29.461] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:50:0) after 1m0s
[23:03:29.499] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:50:0)
[23:03:29.499] Timer tick, asking Buildbucket for the build status
[23:03:29.611] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:29.611] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:51:0) after 1m0s
[23:04:29.689] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:51:0)
[23:04:29.689] Timer tick, asking Buildbucket for the build status
[23:04:29.768] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:29.768] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:52:0) after 1m0s
[23:05:29.861] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:52:0)
[23:05:29.861] Timer tick, asking Buildbucket for the build status
[23:05:29.956] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:29.956] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:53:0) after 1m0s
[23:06:30.065] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:53:0)
[23:06:30.065] Timer tick, asking Buildbucket for the build status
[23:06:30.157] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:30.157] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:54:0) after 1m0s
[23:07:30.250] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:54:0)
[23:07:30.250] Timer tick, asking Buildbucket for the build status
[23:07:30.338] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:30.338] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:55:0) after 1m0s
[23:08:30.401] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:55:0)
[23:08:30.401] Timer tick, asking Buildbucket for the build status
[23:08:30.508] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:30.508] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:56:0) after 1m0s
[23:09:30.561] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:56:0)
[23:09:30.561] Timer tick, asking Buildbucket for the build status
[23:09:30.650] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:30.650] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:57:0) after 1m0s
[23:10:30.691] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:57:0)
[23:10:30.691] Timer tick, asking Buildbucket for the build status
[23:10:30.823] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:30.823] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:58:0) after 1m0s
[23:11:30.899] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:58:0)
[23:11:30.899] Timer tick, asking Buildbucket for the build status
[23:11:32.575] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:32.575] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:59:0) after 1m0s
[23:12:32.715] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:59:0)
[23:12:32.715] Timer tick, asking Buildbucket for the build status
[23:12:32.779] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:32.779] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:60:0) after 1m0s
[23:13:32.827] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:60:0)
[23:13:32.827] Timer tick, asking Buildbucket for the build status
[23:13:32.908] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:32.908] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:61:0) after 1m0s
[23:14:32.927] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:61:0)
[23:14:32.927] Timer tick, asking Buildbucket for the build status
[23:14:33.021] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:33.021] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:62:0) after 1m0s
[23:15:33.068] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:62:0)
[23:15:33.068] Timer tick, asking Buildbucket for the build status
[23:15:33.153] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:33.153] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:63:0) after 1m0s
[23:16:33.253] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:63:0)
[23:16:33.253] Timer tick, asking Buildbucket for the build status
[23:16:33.340] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:33.340] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:64:0) after 1m0s
[23:17:33.363] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:64:0)
[23:17:33.363] Timer tick, asking Buildbucket for the build status
[23:17:33.466] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:33.466] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:65:0) after 1m0s
[23:18:33.571] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:65:0)
[23:18:33.571] Timer tick, asking Buildbucket for the build status
[23:18:33.671] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:33.671] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:66:0) after 1m0s
[23:19:33.853] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:66:0)
[23:19:33.853] Timer tick, asking Buildbucket for the build status
[23:19:33.961] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:33.961] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:67:0) after 1m0s
[23:20:33.979] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:67:0)
[23:20:33.979] Timer tick, asking Buildbucket for the build status
[23:20:34.067] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:34.067] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:68:0) after 1m0s
[23:21:34.100] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:68:0)
[23:21:34.100] Timer tick, asking Buildbucket for the build status
[23:21:34.227] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:34.227] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:69:0) after 1m0s
[23:22:34.392] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:69:0)
[23:22:34.392] Timer tick, asking Buildbucket for the build status
[23:22:34.518] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:34.518] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:70:0) after 1m0s
[23:23:34.546] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:70:0)
[23:23:34.546] Timer tick, asking Buildbucket for the build status
[23:23:34.619] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:34.619] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:71:0) after 1m0s
[23:24:34.691] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:71:0)
[23:24:34.691] Timer tick, asking Buildbucket for the build status
[23:24:34.782] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:34.782] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:72:0) after 1m0s
[23:25:34.893] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:72:0)
[23:25:34.893] Timer tick, asking Buildbucket for the build status
[23:25:34.967] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:34.967] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:73:0) after 1m0s
[23:26:35.051] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:73:0)
[23:26:35.051] Timer tick, asking Buildbucket for the build status
[23:26:35.135] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:35.135] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:74:0) after 1m0s
[23:27:35.163] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:74:0)
[23:27:35.163] Timer tick, asking Buildbucket for the build status
[23:27:35.248] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:35.248] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:75:0) after 1m0s
[23:28:35.270] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:75:0)
[23:28:35.270] Timer tick, asking Buildbucket for the build status
[23:28:35.363] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:35.363] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:76:0) after 1m0s
[23:29:35.464] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:76:0)
[23:29:35.464] Timer tick, asking Buildbucket for the build status
[23:29:35.538] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:35.538] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:77:0) after 1m0s
[23:30:35.698] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:77:0)
[23:30:35.698] Timer tick, asking Buildbucket for the build status
[23:30:35.829] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:35.829] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:78:0) after 1m0s
[23:31:35.853] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:78:0)
[23:31:35.853] Timer tick, asking Buildbucket for the build status
[23:31:35.925] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:35.925] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:79:0) after 1m0s
[23:32:35.970] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:79:0)
[23:32:35.970] Timer tick, asking Buildbucket for the build status
[23:32:36.042] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:36.042] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:80:0) after 1m0s
[23:33:36.079] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:80:0)
[23:33:36.079] Timer tick, asking Buildbucket for the build status
[23:33:36.160] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:36.160] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:81:0) after 1m0s
[23:34:36.248] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:81:0)
[23:34:36.248] Timer tick, asking Buildbucket for the build status
[23:34:36.326] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:36.326] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:82:0) after 1m0s
[23:35:36.353] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:82:0)
[23:35:36.353] Timer tick, asking Buildbucket for the build status
[23:35:36.441] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:36.441] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:83:0) after 1m0s
[23:36:36.469] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:83:0)
[23:36:36.469] Timer tick, asking Buildbucket for the build status
[23:36:36.547] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:36.547] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:84:0) after 1m0s
[23:37:36.585] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:84:0)
[23:37:36.585] Timer tick, asking Buildbucket for the build status
[23:37:36.691] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:36.691] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:85:0) after 1m0s
[23:38:36.814] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:85:0)
[23:38:36.814] Timer tick, asking Buildbucket for the build status
[23:38:36.884] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:36.884] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:86:0) after 1m0s
[23:39:36.963] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:86:0)
[23:39:36.963] Timer tick, asking Buildbucket for the build status
[23:39:37.064] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:37.064] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:87:0) after 1m0s
[23:40:37.095] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:87:0)
[23:40:37.095] Timer tick, asking Buildbucket for the build status
[23:40:37.180] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:37.180] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:88:0) after 1m0s
[23:41:37.283] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:88:0)
[23:41:37.283] Timer tick, asking Buildbucket for the build status
[23:41:37.362] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:37.362] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:89:0) after 1m0s
[23:42:37.385] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:89:0)
[23:42:37.385] Timer tick, asking Buildbucket for the build status
[23:42:37.562] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:37.562] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:90:0) after 1m0s
[23:43:37.695] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:90:0)
[23:43:37.695] Timer tick, asking Buildbucket for the build status
[23:43:37.796] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:37.796] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:91:0) after 1m0s
[23:44:37.892] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:91:0)
[23:44:37.892] Timer tick, asking Buildbucket for the build status
[23:44:38.063] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:38.063] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:92:0) after 1m0s
[23:45:38.094] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:92:0)
[23:45:38.094] Timer tick, asking Buildbucket for the build status
[23:45:38.181] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:38.181] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:93:0) after 1m0s
[23:46:38.282] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:93:0)
[23:46:38.282] Timer tick, asking Buildbucket for the build status
[23:46:38.449] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:38.449] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:94:0) after 1m0s
[23:47:38.478] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:94:0)
[23:47:38.478] Timer tick, asking Buildbucket for the build status
[23:47:38.559] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:38.559] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:95:0) after 1m0s
[23:48:38.582] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:95:0)
[23:48:38.582] Timer tick, asking Buildbucket for the build status
[23:48:38.665] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:38.665] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:96:0) after 1m0s
[23:49:38.706] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:96:0)
[23:49:38.706] Timer tick, asking Buildbucket for the build status
[23:49:38.783] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:38.783] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:97:0) after 1m0s
[23:50:38.882] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:97:0)
[23:50:38.882] Timer tick, asking Buildbucket for the build status
[23:50:39.068] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:39.068] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:98:0) after 1m0s
[23:51:39.095] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:98:0)
[23:51:39.095] Timer tick, asking Buildbucket for the build status
[23:51:39.199] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:39.199] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:99:0) after 1m0s
[23:52:39.232] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:99:0)
[23:52:39.232] Timer tick, asking Buildbucket for the build status
[23:52:39.344] Build 8853652463424712512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:39.344] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:100:0) after 1m0s
[23:53:11.690] Received PubSub notification, asking Buildbucket for the build status
[23:53:11.781] Build 8853652463424712512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:39.385] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:100:0)
[23:53:39.385] Timer tick, asking Buildbucket for the build status
[23:53:39.473] Build 8853652463424712512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:39.473] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:102:0) after 1m0s
[23:54:39.495] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:102:0)
[23:54:39.495] Timer tick, asking Buildbucket for the build status
[23:54:39.574] Build 8853652463424712512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:39.574] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:103:0) after 1m0s
[23:55:39.650] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:103:0)
[23:55:39.650] Timer tick, asking Buildbucket for the build status
[23:55:39.735] Build 8853652463424712512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:39.735] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:104:0) after 1m0s
[23:56:39.772] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:104:0)
[23:56:39.772] Timer tick, asking Buildbucket for the build status
[23:56:39.853] Build 8853652463424712512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:39.853] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:105:0) after 1m0s
[23:57:39.900] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:105:0)
[23:57:39.900] Timer tick, asking Buildbucket for the build status
[23:57:40.045] Build 8853652463424712512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:40.045] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:106:0) after 1m0s
[23:58:40.081] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:106:0)
[23:58:40.081] Timer tick, asking Buildbucket for the build status
[23:58:40.170] Build 8853652463424712512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:40.170] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:107:0) after 1m0s
[23:59:40.251] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:107:0)
[23:59:40.251] Timer tick, asking Buildbucket for the build status
[23:59:40.357] Build 8853652463424712512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:40.357] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019082525387170928:108:0) after 1m0s
[23:59:58.585] Received PubSub notification, asking Buildbucket for the build status
[23:59:58.668] Build 8853652463424712512: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:59:58.668] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1614902397277449",
"created_by": "project:infra",
"created_ts": "1614896061452497",
"id": "8853652463424712512",
"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\": \"f314e6e2cdf2b4e33d8c9eabde2f949af4378459\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f314e6e2cdf2b4e33d8c9eabde2f949af4378459\", \"title\": \"f314e6e2cdf2b4e33d8c9eabde2f949af4378459\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/f314e6e2cdf2b4e33d8c9eabde2f949af4378459\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b4d7e3699f917ceb48567e287c9d3dc6805013db\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b4d7e3699f917ceb48567e287c9d3dc6805013db\", \"title\": \"b4d7e3699f917ceb48567e287c9d3dc6805013db\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b4d7e3699f917ceb48567e287c9d3dc6805013db\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0c8f6257b8202d4a9b890f33585e83bbdb59bab3\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0c8f6257b8202d4a9b890f33585e83bbdb59bab3\", \"title\": \"0c8f6257b8202d4a9b890f33585e83bbdb59bab3\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0c8f6257b8202d4a9b890f33585e83bbdb59bab3\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"629b064811b082c4c9f185c3999c7d5d2f9e0d8a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@629b064811b082c4c9f185c3999c7d5d2f9e0d8a\", \"title\": \"629b064811b082c4c9f185c3999c7d5d2f9e0d8a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/629b064811b082c4c9f185c3999c7d5d2f9e0d8a\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"629b064811b082c4c9f185c3999c7d5d2f9e0d8a\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"629b064811b082c4c9f185c3999c7d5d2f9e0d8a\", \"got_revision_cp\": \"refs/heads/master@{#39044}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_044445a3bc7a4bfe795493d7956aa02cd526172de1fa7300f69fe152e82ea631_v2\", \"builder_5de1032f0e9ef497433aad28840ee258c037976023b410f653133cee0ad04e2c_v2\", \"builder_82ed111fed7ea8169e482f00b5f3e5338a65daa41208585de67cb0a736b1d538_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"builder_d38bdc976f893fc6930479dce8a6d7dc793b2a74fe1392d4af00713a5c0bb46e_v2\", \"git\", \"goma\", \"infra_gclient_with_go\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-46tn\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5736-3ab5025\"], \"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": "1614901987752276",
"status": "COMPLETED",
"status_changed_ts": "1614901988183076",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18783",
"builder:infra-continuous-win10-64",
"buildset:commit/git/629b064811b082c4c9f185c3999c7d5d2f9e0d8a",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/629b064811b082c4c9f185c3999c7d5d2f9e0d8a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019082525387170928",
"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/8853652463424712512/+/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:52181fe71de6a810",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1614902397278041",
"url": "https://luci-milo-dev.appspot.com/b/8853652463424712512",
"utcnow_ts": "1614902398661181"
}
}
[23:59:58.668] Invocation finished in 1h45m38.471397917s with status SUCCEEDED