[23:26:38.054] New invocation is queued and will start shortly
[23:26:39.121] Starting the invocation (attempt 1)
[23:26:39.157] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[23:26:39.157] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[23:26:39.157] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9018624992000005024",
"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\":\"c0ddb5cd9391505ecfc94db48bf155c9d4cf4444\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c0ddb5cd9391505ecfc94db48bf155c9d4cf4444\",\"title\":\"c0ddb5cd9391505ecfc94db48bf155c9d4cf4444\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/c0ddb5cd9391505ecfc94db48bf155c9d4cf4444\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"fd3cdd50e892675b87f094eb1c9fcd0e4159a713\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@fd3cdd50e892675b87f094eb1c9fcd0e4159a713\",\"title\":\"fd3cdd50e892675b87f094eb1c9fcd0e4159a713\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/fd3cdd50e892675b87f094eb1c9fcd0e4159a713\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"11b8d9e1b976c7ef4dd60521c87d00a8970f889b\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@11b8d9e1b976c7ef4dd60521c87d00a8970f889b\",\"title\":\"11b8d9e1b976c7ef4dd60521c87d00a8970f889b\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/11b8d9e1b976c7ef4dd60521c87d00a8970f889b\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"f2e55e656ec07e03f2d46e16737bb33f1b9edd4a\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f2e55e656ec07e03f2d46e16737bb33f1b9edd4a\",\"title\":\"f2e55e656ec07e03f2d46e16737bb33f1b9edd4a\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/f2e55e656ec07e03f2d46e16737bb33f1b9edd4a\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"f3bdf9784f7e29e61124def488d54c432f0a59af\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f3bdf9784f7e29e61124def488d54c432f0a59af\",\"title\":\"f3bdf9784f7e29e61124def488d54c432f0a59af\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/f3bdf9784f7e29e61124def488d54c432f0a59af\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"955573a86e98cde03769e6eab00bfdada3d5af84\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@955573a86e98cde03769e6eab00bfdada3d5af84\",\"title\":\"955573a86e98cde03769e6eab00bfdada3d5af84\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/955573a86e98cde03769e6eab00bfdada3d5af84\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"d5424c0cc08fa51b48a41600936ea618d0e753a1\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d5424c0cc08fa51b48a41600936ea618d0e753a1\",\"title\":\"d5424c0cc08fa51b48a41600936ea618d0e753a1\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/d5424c0cc08fa51b48a41600936ea618d0e753a1\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"d5424c0cc08fa51b48a41600936ea618d0e753a1\"}}",
"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:9018624992000005024",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/d5424c0cc08fa51b48a41600936ea618d0e753a1",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/d5424c0cc08fa51b48a41600936ea618d0e753a1",
"gitiles_ref:refs/heads/master"
]
}
[23:26:39.807] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1615332399267711",
"id": "8853194930064220080",
"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\": \"c0ddb5cd9391505ecfc94db48bf155c9d4cf4444\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c0ddb5cd9391505ecfc94db48bf155c9d4cf4444\", \"title\": \"c0ddb5cd9391505ecfc94db48bf155c9d4cf4444\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/c0ddb5cd9391505ecfc94db48bf155c9d4cf4444\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"fd3cdd50e892675b87f094eb1c9fcd0e4159a713\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@fd3cdd50e892675b87f094eb1c9fcd0e4159a713\", \"title\": \"fd3cdd50e892675b87f094eb1c9fcd0e4159a713\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/fd3cdd50e892675b87f094eb1c9fcd0e4159a713\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"11b8d9e1b976c7ef4dd60521c87d00a8970f889b\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@11b8d9e1b976c7ef4dd60521c87d00a8970f889b\", \"title\": \"11b8d9e1b976c7ef4dd60521c87d00a8970f889b\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/11b8d9e1b976c7ef4dd60521c87d00a8970f889b\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f2e55e656ec07e03f2d46e16737bb33f1b9edd4a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f2e55e656ec07e03f2d46e16737bb33f1b9edd4a\", \"title\": \"f2e55e656ec07e03f2d46e16737bb33f1b9edd4a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/f2e55e656ec07e03f2d46e16737bb33f1b9edd4a\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f3bdf9784f7e29e61124def488d54c432f0a59af\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f3bdf9784f7e29e61124def488d54c432f0a59af\", \"title\": \"f3bdf9784f7e29e61124def488d54c432f0a59af\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/f3bdf9784f7e29e61124def488d54c432f0a59af\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"955573a86e98cde03769e6eab00bfdada3d5af84\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@955573a86e98cde03769e6eab00bfdada3d5af84\", \"title\": \"955573a86e98cde03769e6eab00bfdada3d5af84\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/955573a86e98cde03769e6eab00bfdada3d5af84\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"d5424c0cc08fa51b48a41600936ea618d0e753a1\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d5424c0cc08fa51b48a41600936ea618d0e753a1\", \"title\": \"d5424c0cc08fa51b48a41600936ea618d0e753a1\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/d5424c0cc08fa51b48a41600936ea618d0e753a1\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"d5424c0cc08fa51b48a41600936ea618d0e753a1\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615332399681352",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18822",
"builder:infra-continuous-win10-64",
"buildset:commit/git/d5424c0cc08fa51b48a41600936ea618d0e753a1",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/d5424c0cc08fa51b48a41600936ea618d0e753a1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018624992000005024",
"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/8853194930064220080/+/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": "1615332399681456",
"url": "https://luci-milo-dev.appspot.com/b/8853194930064220080",
"utcnow_ts": "1615332399798849"
}
}
[23:26:39.807] Task URL: https://luci-milo-dev.appspot.com/b/8853194930064220080
[23:26:39.807] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:2:0) after 1m0s
[23:27:39.843] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:2:0)
[23:27:39.843] Timer tick, asking Buildbucket for the build status
[23:27:40.008] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:40.008] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:3:0) after 1m0s
[23:28:40.107] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:3:0)
[23:28:40.107] Timer tick, asking Buildbucket for the build status
[23:28:40.276] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:40.276] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:4:0) after 1m0s
[23:29:40.337] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:4:0)
[23:29:40.337] Timer tick, asking Buildbucket for the build status
[23:29:40.420] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:40.420] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:5:0) after 1m0s
[23:30:40.441] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:5:0)
[23:30:40.441] Timer tick, asking Buildbucket for the build status
[23:30:40.539] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:40.539] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:6:0) after 1m0s
[23:31:40.559] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:6:0)
[23:31:40.559] Timer tick, asking Buildbucket for the build status
[23:31:40.641] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:40.641] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:7:0) after 1m0s
[23:32:40.748] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:7:0)
[23:32:40.748] Timer tick, asking Buildbucket for the build status
[23:32:40.836] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:40.836] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:8:0) after 1m0s
[23:33:40.905] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:8:0)
[23:33:40.905] Timer tick, asking Buildbucket for the build status
[23:33:41.022] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:41.022] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:9:0) after 1m0s
[23:34:41.111] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:9:0)
[23:34:41.111] Timer tick, asking Buildbucket for the build status
[23:34:41.192] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:41.192] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:10:0) after 1m0s
[23:35:41.293] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:10:0)
[23:35:41.293] Timer tick, asking Buildbucket for the build status
[23:35:41.426] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:41.426] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:11:0) after 1m0s
[23:36:41.491] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:11:0)
[23:36:41.491] Timer tick, asking Buildbucket for the build status
[23:36:41.584] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:41.584] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:12:0) after 1m0s
[23:37:41.608] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:12:0)
[23:37:41.608] Timer tick, asking Buildbucket for the build status
[23:37:41.701] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:41.701] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:13:0) after 1m0s
[23:38:41.804] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:13:0)
[23:38:41.804] Timer tick, asking Buildbucket for the build status
[23:38:41.905] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:41.905] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:14:0) after 1m0s
[23:39:41.943] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:14:0)
[23:39:41.943] Timer tick, asking Buildbucket for the build status
[23:39:42.021] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:42.021] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:15:0) after 1m0s
[23:40:42.129] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:15:0)
[23:40:42.129] Timer tick, asking Buildbucket for the build status
[23:40:42.247] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:42.247] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:16:0) after 1m0s
[23:41:42.265] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:16:0)
[23:41:42.265] Timer tick, asking Buildbucket for the build status
[23:41:42.420] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:42.420] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:17:0) after 1m0s
[23:42:42.495] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:17:0)
[23:42:42.495] Timer tick, asking Buildbucket for the build status
[23:42:42.631] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:42.631] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:18:0) after 1m0s
[23:43:42.699] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:18:0)
[23:43:42.699] Timer tick, asking Buildbucket for the build status
[23:43:42.806] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:42.806] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:19:0) after 1m0s
[23:44:42.892] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:19:0)
[23:44:42.892] Timer tick, asking Buildbucket for the build status
[23:44:42.982] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:42.982] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:20:0) after 1m0s
[23:45:43.050] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:20:0)
[23:45:43.050] Timer tick, asking Buildbucket for the build status
[23:45:43.157] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:43.157] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:21:0) after 1m0s
[23:46:43.250] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:21:0)
[23:46:43.250] Timer tick, asking Buildbucket for the build status
[23:46:43.351] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:43.351] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:22:0) after 1m0s
[23:47:43.448] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:22:0)
[23:47:43.448] Timer tick, asking Buildbucket for the build status
[23:47:43.608] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:43.608] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:23:0) after 1m0s
[23:48:43.693] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:23:0)
[23:48:43.693] Timer tick, asking Buildbucket for the build status
[23:48:43.805] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:43.805] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:24:0) after 1m0s
[23:49:43.842] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:24:0)
[23:49:43.842] Timer tick, asking Buildbucket for the build status
[23:49:43.951] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:43.951] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:25:0) after 1m0s
[23:50:44.011] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:25:0)
[23:50:44.011] Timer tick, asking Buildbucket for the build status
[23:50:44.091] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:44.091] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:26:0) after 1m0s
[23:51:44.205] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:26:0)
[23:51:44.205] Timer tick, asking Buildbucket for the build status
[23:51:44.315] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:44.315] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:27:0) after 1m0s
[23:52:44.342] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:27:0)
[23:52:44.342] Timer tick, asking Buildbucket for the build status
[23:52:44.532] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:44.532] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:28:0) after 1m0s
[23:53:44.550] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:28:0)
[23:53:44.550] Timer tick, asking Buildbucket for the build status
[23:53:44.724] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:44.724] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:29:0) after 1m0s
[23:54:44.745] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:29:0)
[23:54:44.745] Timer tick, asking Buildbucket for the build status
[23:54:44.902] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:44.902] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:30:0) after 1m0s
[23:55:45.005] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:30:0)
[23:55:45.005] Timer tick, asking Buildbucket for the build status
[23:55:45.096] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:45.096] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:31:0) after 1m0s
[23:56:45.212] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:31:0)
[23:56:45.212] Timer tick, asking Buildbucket for the build status
[23:56:45.307] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:45.307] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:32:0) after 1m0s
[23:57:45.339] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:32:0)
[23:57:45.339] Timer tick, asking Buildbucket for the build status
[23:57:45.418] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:45.418] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:33:0) after 1m0s
[23:58:45.494] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:33:0)
[23:58:45.494] Timer tick, asking Buildbucket for the build status
[23:58:45.620] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:45.620] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:34:0) after 1m0s
[23:59:45.641] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:34:0)
[23:59:45.641] Timer tick, asking Buildbucket for the build status
[23:59:45.812] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:45.812] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:35:0) after 1m0s
[00:00:45.839] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:35:0)
[00:00:45.839] Timer tick, asking Buildbucket for the build status
[00:00:45.957] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:45.957] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:36:0) after 1m0s
[00:01:45.994] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:36:0)
[00:01:45.994] Timer tick, asking Buildbucket for the build status
[00:01:46.092] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:46.092] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:37:0) after 1m0s
[00:02:46.204] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:37:0)
[00:02:46.204] Timer tick, asking Buildbucket for the build status
[00:02:46.284] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:46.284] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:38:0) after 1m0s
[00:03:46.343] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:38:0)
[00:03:46.343] Timer tick, asking Buildbucket for the build status
[00:03:46.420] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:46.420] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:39:0) after 1m0s
[00:04:46.446] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:39:0)
[00:04:46.446] Timer tick, asking Buildbucket for the build status
[00:04:46.635] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:46.635] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:40:0) after 1m0s
[00:05:46.765] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:40:0)
[00:05:46.766] Timer tick, asking Buildbucket for the build status
[00:05:46.857] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:46.857] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:41:0) after 1m0s
[00:06:46.897] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:41:0)
[00:06:46.898] Timer tick, asking Buildbucket for the build status
[00:06:47.003] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:47.003] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:42:0) after 1m0s
[00:07:47.050] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:42:0)
[00:07:47.050] Timer tick, asking Buildbucket for the build status
[00:07:47.145] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:47.145] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:43:0) after 1m0s
[00:08:47.195] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:43:0)
[00:08:47.195] Timer tick, asking Buildbucket for the build status
[00:08:47.322] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:47.322] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:44:0) after 1m0s
[00:09:47.357] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:44:0)
[00:09:47.357] Timer tick, asking Buildbucket for the build status
[00:09:47.439] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:47.439] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:45:0) after 1m0s
[00:10:47.490] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:45:0)
[00:10:47.490] Timer tick, asking Buildbucket for the build status
[00:10:47.586] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:47.586] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:46:0) after 1m0s
[00:11:47.705] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:46:0)
[00:11:47.705] Timer tick, asking Buildbucket for the build status
[00:11:47.808] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:47.808] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:47:0) after 1m0s
[00:12:47.835] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:47:0)
[00:12:47.835] Timer tick, asking Buildbucket for the build status
[00:12:47.919] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:47.919] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:48:0) after 1m0s
[00:13:47.942] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:48:0)
[00:13:47.942] Timer tick, asking Buildbucket for the build status
[00:13:48.036] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:48.036] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:49:0) after 1m0s
[00:14:48.094] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:49:0)
[00:14:48.094] Timer tick, asking Buildbucket for the build status
[00:14:48.211] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:48.211] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:50:0) after 1m0s
[00:15:48.310] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:50:0)
[00:15:48.310] Timer tick, asking Buildbucket for the build status
[00:15:48.389] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:48.390] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:51:0) after 1m0s
[00:16:48.413] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:51:0)
[00:16:48.413] Timer tick, asking Buildbucket for the build status
[00:16:48.492] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:48.492] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:52:0) after 1m0s
[00:17:48.546] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:52:0)
[00:17:48.546] Timer tick, asking Buildbucket for the build status
[00:17:48.699] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:48.699] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:53:0) after 1m0s
[00:18:48.734] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:53:0)
[00:18:48.734] Timer tick, asking Buildbucket for the build status
[00:18:48.833] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:48.833] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:54:0) after 1m0s
[00:19:48.934] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:54:0)
[00:19:48.934] Timer tick, asking Buildbucket for the build status
[00:19:49.019] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:49.019] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:55:0) after 1m0s
[00:20:49.042] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:55:0)
[00:20:49.042] Timer tick, asking Buildbucket for the build status
[00:20:49.142] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:49.142] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:56:0) after 1m0s
[00:21:49.208] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:56:0)
[00:21:49.209] Timer tick, asking Buildbucket for the build status
[00:21:49.359] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:49.359] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:57:0) after 1m0s
[00:22:49.381] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:57:0)
[00:22:49.381] Timer tick, asking Buildbucket for the build status
[00:22:49.493] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:49.493] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:58:0) after 1m0s
[00:23:49.513] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:58:0)
[00:23:49.513] Timer tick, asking Buildbucket for the build status
[00:23:49.596] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:49.596] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:59:0) after 1m0s
[00:24:49.647] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:59:0)
[00:24:49.647] Timer tick, asking Buildbucket for the build status
[00:24:49.743] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:49.743] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:60:0) after 1m0s
[00:25:49.793] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:60:0)
[00:25:49.793] Timer tick, asking Buildbucket for the build status
[00:25:49.898] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:49.898] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:61:0) after 1m0s
[00:26:49.994] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:61:0)
[00:26:49.994] Timer tick, asking Buildbucket for the build status
[00:26:50.109] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:50.109] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:62:0) after 1m0s
[00:27:50.128] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:62:0)
[00:27:50.128] Timer tick, asking Buildbucket for the build status
[00:27:50.211] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:50.211] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:63:0) after 1m0s
[00:28:50.245] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:63:0)
[00:28:50.245] Timer tick, asking Buildbucket for the build status
[00:28:50.531] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:50.531] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:64:0) after 1m0s
[00:29:50.555] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:64:0)
[00:29:50.555] Timer tick, asking Buildbucket for the build status
[00:29:50.643] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:50.643] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:65:0) after 1m0s
[00:30:50.751] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:65:0)
[00:30:50.751] Timer tick, asking Buildbucket for the build status
[00:30:50.840] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:50.840] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:66:0) after 1m0s
[00:31:50.965] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:66:0)
[00:31:50.965] Timer tick, asking Buildbucket for the build status
[00:31:51.173] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:51.173] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:67:0) after 1m0s
[00:32:51.249] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:67:0)
[00:32:51.249] Timer tick, asking Buildbucket for the build status
[00:32:51.389] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:51.389] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:68:0) after 1m0s
[00:33:51.413] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:68:0)
[00:33:51.413] Timer tick, asking Buildbucket for the build status
[00:33:51.548] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:51.548] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:69:0) after 1m0s
[00:34:51.648] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:69:0)
[00:34:51.648] Timer tick, asking Buildbucket for the build status
[00:34:51.814] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:51.814] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:70:0) after 1m0s
[00:35:51.840] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:70:0)
[00:35:51.840] Timer tick, asking Buildbucket for the build status
[00:35:51.941] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:51.941] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:71:0) after 1m0s
[00:36:51.993] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:71:0)
[00:36:51.993] Timer tick, asking Buildbucket for the build status
[00:36:52.069] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:52.069] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:72:0) after 1m0s
[00:37:52.133] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:72:0)
[00:37:52.133] Timer tick, asking Buildbucket for the build status
[00:37:52.223] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:52.223] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:73:0) after 1m0s
[00:38:52.249] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:73:0)
[00:38:52.249] Timer tick, asking Buildbucket for the build status
[00:38:52.350] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:52.350] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:74:0) after 1m0s
[00:39:52.378] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:74:0)
[00:39:52.378] Timer tick, asking Buildbucket for the build status
[00:39:52.481] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:52.481] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:75:0) after 1m0s
[00:40:52.505] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:75:0)
[00:40:52.505] Timer tick, asking Buildbucket for the build status
[00:40:52.597] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:52.597] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:76:0) after 1m0s
[00:41:52.701] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:76:0)
[00:41:52.701] Timer tick, asking Buildbucket for the build status
[00:41:52.788] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:52.788] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:77:0) after 1m0s
[00:42:52.903] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:77:0)
[00:42:52.903] Timer tick, asking Buildbucket for the build status
[00:42:53.027] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:53.027] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:78:0) after 1m0s
[00:43:53.104] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:78:0)
[00:43:53.104] Timer tick, asking Buildbucket for the build status
[00:43:53.201] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:53.201] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:79:0) after 1m0s
[00:44:53.311] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:79:0)
[00:44:53.311] Timer tick, asking Buildbucket for the build status
[00:44:53.403] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:53.403] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:80:0) after 1m0s
[00:45:53.495] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:80:0)
[00:45:53.495] Timer tick, asking Buildbucket for the build status
[00:45:53.575] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:53.575] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:81:0) after 1m0s
[00:46:53.615] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:81:0)
[00:46:53.615] Timer tick, asking Buildbucket for the build status
[00:46:53.703] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:53.704] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:82:0) after 1m0s
[00:47:53.805] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:82:0)
[00:47:53.805] Timer tick, asking Buildbucket for the build status
[00:47:53.932] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:53.932] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:83:0) after 1m0s
[00:48:54.039] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:83:0)
[00:48:54.039] Timer tick, asking Buildbucket for the build status
[00:48:54.218] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:54.218] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:84:0) after 1m0s
[00:49:54.244] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:84:0)
[00:49:54.244] Timer tick, asking Buildbucket for the build status
[00:49:54.314] Build 8853194930064220080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:54.314] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:85:0) after 1m0s
[00:50:20.810] Received PubSub notification, asking Buildbucket for the build status
[00:50:20.895] Build 8853194930064220080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:54.402] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:85:0)
[00:50:54.402] Timer tick, asking Buildbucket for the build status
[00:50:54.491] Build 8853194930064220080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:54.491] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:87:0) after 1m0s
[00:51:54.596] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:87:0)
[00:51:54.596] Timer tick, asking Buildbucket for the build status
[00:51:54.700] Build 8853194930064220080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:54.700] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:88:0) after 1m0s
[00:52:54.813] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:88:0)
[00:52:54.813] Timer tick, asking Buildbucket for the build status
[00:52:54.926] Build 8853194930064220080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:54.926] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:89:0) after 1m0s
[00:53:55.036] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:89:0)
[00:53:55.036] Timer tick, asking Buildbucket for the build status
[00:53:55.135] Build 8853194930064220080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:55.135] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:90:0) after 1m0s
[00:54:55.204] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:90:0)
[00:54:55.204] Timer tick, asking Buildbucket for the build status
[00:54:55.281] Build 8853194930064220080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:55.281] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:91:0) after 1m0s
[00:55:55.310] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:91:0)
[00:55:55.310] Timer tick, asking Buildbucket for the build status
[00:55:55.404] Build 8853194930064220080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:55.404] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:92:0) after 1m0s
[00:56:55.436] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:92:0)
[00:56:55.436] Timer tick, asking Buildbucket for the build status
[00:56:55.518] Build 8853194930064220080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:55.518] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:93:0) after 1m0s
[00:57:55.539] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:93:0)
[00:57:55.539] Timer tick, asking Buildbucket for the build status
[00:57:55.642] Build 8853194930064220080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:55.642] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018624992000005024:94:0) after 1m0s
[00:58:24.710] Received PubSub notification, asking Buildbucket for the build status
[00:58:24.796] Build 8853194930064220080: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:58:24.796] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1615337904361706",
"created_by": "project:infra",
"created_ts": "1615332399267711",
"failure_reason": "BUILD_FAILURE",
"id": "8853194930064220080",
"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\": \"c0ddb5cd9391505ecfc94db48bf155c9d4cf4444\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c0ddb5cd9391505ecfc94db48bf155c9d4cf4444\", \"title\": \"c0ddb5cd9391505ecfc94db48bf155c9d4cf4444\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/c0ddb5cd9391505ecfc94db48bf155c9d4cf4444\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"fd3cdd50e892675b87f094eb1c9fcd0e4159a713\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@fd3cdd50e892675b87f094eb1c9fcd0e4159a713\", \"title\": \"fd3cdd50e892675b87f094eb1c9fcd0e4159a713\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/fd3cdd50e892675b87f094eb1c9fcd0e4159a713\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"11b8d9e1b976c7ef4dd60521c87d00a8970f889b\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@11b8d9e1b976c7ef4dd60521c87d00a8970f889b\", \"title\": \"11b8d9e1b976c7ef4dd60521c87d00a8970f889b\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/11b8d9e1b976c7ef4dd60521c87d00a8970f889b\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f2e55e656ec07e03f2d46e16737bb33f1b9edd4a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f2e55e656ec07e03f2d46e16737bb33f1b9edd4a\", \"title\": \"f2e55e656ec07e03f2d46e16737bb33f1b9edd4a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/f2e55e656ec07e03f2d46e16737bb33f1b9edd4a\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f3bdf9784f7e29e61124def488d54c432f0a59af\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f3bdf9784f7e29e61124def488d54c432f0a59af\", \"title\": \"f3bdf9784f7e29e61124def488d54c432f0a59af\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/f3bdf9784f7e29e61124def488d54c432f0a59af\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"955573a86e98cde03769e6eab00bfdada3d5af84\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@955573a86e98cde03769e6eab00bfdada3d5af84\", \"title\": \"955573a86e98cde03769e6eab00bfdada3d5af84\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/955573a86e98cde03769e6eab00bfdada3d5af84\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"d5424c0cc08fa51b48a41600936ea618d0e753a1\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d5424c0cc08fa51b48a41600936ea618d0e753a1\", \"title\": \"d5424c0cc08fa51b48a41600936ea618d0e753a1\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/d5424c0cc08fa51b48a41600936ea618d0e753a1\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"d5424c0cc08fa51b48a41600936ea618d0e753a1\"}}",
"project": "infra",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('infra go tests') (retcode: 1)\"}, \"properties\": {\"got_revision\": \"d5424c0cc08fa51b48a41600936ea618d0e753a1\", \"got_revision_cp\": \"refs/heads/master@{#39176}\"}, \"swarming\": {\"bot_dimensions\": {\"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-o51r\"], \"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\": [\"5747-bda4cd8\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}, \"ui\": {\"info\": \"Step('infra go tests') (retcode: 1)\"}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"started_ts": "1615337419128797",
"status": "COMPLETED",
"status_changed_ts": "1615337419491535",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18822",
"builder:infra-continuous-win10-64",
"buildset:commit/git/d5424c0cc08fa51b48a41600936ea618d0e753a1",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/d5424c0cc08fa51b48a41600936ea618d0e753a1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018624992000005024",
"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/8853194930064220080/+/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:523221e38bc4d810",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1615337904362000",
"url": "https://luci-milo-dev.appspot.com/b/8853194930064220080",
"utcnow_ts": "1615337904782973"
}
}
[00:58:24.796] Invocation finished in 1h31m46.757896188s with status FAILED