[22:18:42.088] New invocation is queued and will start shortly
[22:18:43.197] Starting the invocation (attempt 1)
[22:18:43.244] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[22:18:43.266] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[22:18:43.266] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9025061650590973376",
"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\":\"75b40d57094df8c365dec939a30c6eb1b937493b\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@75b40d57094df8c365dec939a30c6eb1b937493b\",\"title\":\"75b40d57094df8c365dec939a30c6eb1b937493b\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/75b40d57094df8c365dec939a30c6eb1b937493b\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"36b482f47d9c4d969cfb643a798d63c03932b4e6\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@36b482f47d9c4d969cfb643a798d63c03932b4e6\",\"title\":\"36b482f47d9c4d969cfb643a798d63c03932b4e6\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/36b482f47d9c4d969cfb643a798d63c03932b4e6\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"81ae84553bd497b21816623138d436e95639e4e2\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@81ae84553bd497b21816623138d436e95639e4e2\",\"title\":\"81ae84553bd497b21816623138d436e95639e4e2\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/81ae84553bd497b21816623138d436e95639e4e2\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"e5516396ad19deaa3ac82e3106f0641255706e2e\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@e5516396ad19deaa3ac82e3106f0641255706e2e\",\"title\":\"e5516396ad19deaa3ac82e3106f0641255706e2e\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/e5516396ad19deaa3ac82e3106f0641255706e2e\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00\",\"title\":\"bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00\"}}",
"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:9025061650590973376",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00",
"gitiles_ref:refs/heads/master"
]
}
[22:18:43.993] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1609193923396675",
"id": "8859631588538897712",
"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\": \"75b40d57094df8c365dec939a30c6eb1b937493b\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@75b40d57094df8c365dec939a30c6eb1b937493b\", \"title\": \"75b40d57094df8c365dec939a30c6eb1b937493b\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/75b40d57094df8c365dec939a30c6eb1b937493b\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"36b482f47d9c4d969cfb643a798d63c03932b4e6\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@36b482f47d9c4d969cfb643a798d63c03932b4e6\", \"title\": \"36b482f47d9c4d969cfb643a798d63c03932b4e6\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/36b482f47d9c4d969cfb643a798d63c03932b4e6\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"81ae84553bd497b21816623138d436e95639e4e2\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@81ae84553bd497b21816623138d436e95639e4e2\", \"title\": \"81ae84553bd497b21816623138d436e95639e4e2\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/81ae84553bd497b21816623138d436e95639e4e2\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"e5516396ad19deaa3ac82e3106f0641255706e2e\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@e5516396ad19deaa3ac82e3106f0641255706e2e\", \"title\": \"e5516396ad19deaa3ac82e3106f0641255706e2e\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/e5516396ad19deaa3ac82e3106f0641255706e2e\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00\", \"title\": \"bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609193923881461",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18221",
"builder:infra-continuous-win10-64",
"buildset:commit/git/bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025061650590973376",
"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/8859631588538897712/+/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": "1609193923881571",
"url": "https://luci-milo-dev.appspot.com/b/8859631588538897712",
"utcnow_ts": "1609193923985510"
}
}
[22:18:43.993] Task URL: https://luci-milo-dev.appspot.com/b/8859631588538897712
[22:18:43.993] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:2:0) after 1m0s
[22:19:44.045] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:2:0)
[22:19:44.045] Timer tick, asking Buildbucket for the build status
[22:19:44.170] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:44.170] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:3:0) after 1m0s
[22:20:44.241] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:3:0)
[22:20:44.241] Timer tick, asking Buildbucket for the build status
[22:20:44.333] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:44.333] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:4:0) after 1m0s
[22:21:44.378] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:4:0)
[22:21:44.378] Timer tick, asking Buildbucket for the build status
[22:21:44.466] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:44.466] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:5:0) after 1m0s
[22:22:44.484] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:5:0)
[22:22:44.484] Timer tick, asking Buildbucket for the build status
[22:22:44.594] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:44.594] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:6:0) after 1m0s
[22:23:44.616] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:6:0)
[22:23:44.616] Timer tick, asking Buildbucket for the build status
[22:23:44.735] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:44.735] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:7:0) after 1m0s
[22:24:44.790] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:7:0)
[22:24:44.790] Timer tick, asking Buildbucket for the build status
[22:24:44.879] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:44.879] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:8:0) after 1m0s
[22:25:44.899] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:8:0)
[22:25:44.899] Timer tick, asking Buildbucket for the build status
[22:25:45.053] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:45.053] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:9:0) after 1m0s
[22:26:45.146] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:9:0)
[22:26:45.146] Timer tick, asking Buildbucket for the build status
[22:26:45.238] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:45.238] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:10:0) after 1m0s
[22:27:45.356] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:10:0)
[22:27:45.356] Timer tick, asking Buildbucket for the build status
[22:27:45.460] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:45.460] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:11:0) after 1m0s
[22:28:45.487] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:11:0)
[22:28:45.487] Timer tick, asking Buildbucket for the build status
[22:28:45.607] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:45.607] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:12:0) after 1m0s
[22:29:45.686] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:12:0)
[22:29:45.686] Timer tick, asking Buildbucket for the build status
[22:29:45.779] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:45.779] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:13:0) after 1m0s
[22:30:45.885] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:13:0)
[22:30:45.885] Timer tick, asking Buildbucket for the build status
[22:30:45.991] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:45.991] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:14:0) after 1m0s
[22:31:46.086] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:14:0)
[22:31:46.086] Timer tick, asking Buildbucket for the build status
[22:31:46.234] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:46.234] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:15:0) after 1m0s
[22:32:46.380] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:15:0)
[22:32:46.380] Timer tick, asking Buildbucket for the build status
[22:32:46.512] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:46.513] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:16:0) after 1m0s
[22:33:46.558] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:16:0)
[22:33:46.558] Timer tick, asking Buildbucket for the build status
[22:33:46.664] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:46.664] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:17:0) after 1m0s
[22:34:46.744] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:17:0)
[22:34:46.744] Timer tick, asking Buildbucket for the build status
[22:34:46.883] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:46.883] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:18:0) after 1m0s
[22:35:46.977] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:18:0)
[22:35:46.977] Timer tick, asking Buildbucket for the build status
[22:35:47.116] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:47.116] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:19:0) after 1m0s
[22:36:47.177] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:19:0)
[22:36:47.177] Timer tick, asking Buildbucket for the build status
[22:36:47.291] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:47.291] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:20:0) after 1m0s
[22:37:47.360] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:20:0)
[22:37:47.360] Timer tick, asking Buildbucket for the build status
[22:37:47.446] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:47.446] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:21:0) after 1m0s
[22:38:47.544] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:21:0)
[22:38:47.544] Timer tick, asking Buildbucket for the build status
[22:38:47.662] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:47.662] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:22:0) after 1m0s
[22:39:47.688] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:22:0)
[22:39:47.688] Timer tick, asking Buildbucket for the build status
[22:39:47.796] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:47.796] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:23:0) after 1m0s
[22:40:47.857] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:23:0)
[22:40:47.857] Timer tick, asking Buildbucket for the build status
[22:40:48.320] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:48.320] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:24:0) after 1m0s
[22:41:48.385] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:24:0)
[22:41:48.385] Timer tick, asking Buildbucket for the build status
[22:41:48.486] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:48.486] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:25:0) after 1m0s
[22:42:48.578] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:25:0)
[22:42:48.578] Timer tick, asking Buildbucket for the build status
[22:42:48.714] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:48.714] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:26:0) after 1m0s
[22:43:48.737] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:26:0)
[22:43:48.737] Timer tick, asking Buildbucket for the build status
[22:43:48.888] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:48.888] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:27:0) after 1m0s
[22:44:48.949] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:27:0)
[22:44:48.949] Timer tick, asking Buildbucket for the build status
[22:44:49.048] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:49.048] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:28:0) after 1m0s
[22:45:49.080] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:28:0)
[22:45:49.080] Timer tick, asking Buildbucket for the build status
[22:45:49.594] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:49.594] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:29:0) after 1m0s
[22:46:49.612] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:29:0)
[22:46:49.612] Timer tick, asking Buildbucket for the build status
[22:46:50.026] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:50.026] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:30:0) after 1m0s
[22:47:50.078] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:30:0)
[22:47:50.078] Timer tick, asking Buildbucket for the build status
[22:47:50.421] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:50.421] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:31:0) after 1m0s
[22:48:50.442] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:31:0)
[22:48:50.442] Timer tick, asking Buildbucket for the build status
[22:48:50.748] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:50.748] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:32:0) after 1m0s
[22:49:50.849] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:32:0)
[22:49:50.849] Timer tick, asking Buildbucket for the build status
[22:49:51.254] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:51.254] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:33:0) after 1m0s
[22:50:51.277] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:33:0)
[22:50:51.277] Timer tick, asking Buildbucket for the build status
[22:50:51.616] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:51.616] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:34:0) after 1m0s
[22:51:51.686] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:34:0)
[22:51:51.686] Timer tick, asking Buildbucket for the build status
[22:51:52.154] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:52.154] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:35:0) after 1m0s
[22:52:52.185] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:35:0)
[22:52:52.185] Timer tick, asking Buildbucket for the build status
[22:52:52.840] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:52.841] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:36:0) after 1m0s
[22:53:52.975] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:36:0)
[22:53:52.975] Timer tick, asking Buildbucket for the build status
[22:53:53.315] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:53.315] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:37:0) after 1m0s
[22:54:53.440] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:37:0)
[22:54:53.440] Timer tick, asking Buildbucket for the build status
[22:54:53.831] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:53.831] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:38:0) after 1m0s
[22:55:53.887] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:38:0)
[22:55:53.887] Timer tick, asking Buildbucket for the build status
[22:55:54.193] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:54.193] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:39:0) after 1m0s
[22:56:54.289] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:39:0)
[22:56:54.289] Timer tick, asking Buildbucket for the build status
[22:56:54.586] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:54.586] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:40:0) after 1m0s
[22:57:54.621] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:40:0)
[22:57:54.621] Timer tick, asking Buildbucket for the build status
[22:57:54.866] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:54.866] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:41:0) after 1m0s
[22:58:54.887] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:41:0)
[22:58:54.887] Timer tick, asking Buildbucket for the build status
[22:58:55.054] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:55.054] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:42:0) after 1m0s
[22:59:55.159] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:42:0)
[22:59:55.159] Timer tick, asking Buildbucket for the build status
[22:59:55.312] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:55.312] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:43:0) after 1m0s
[23:00:55.412] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:43:0)
[23:00:55.413] Timer tick, asking Buildbucket for the build status
[23:00:55.609] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:55.609] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:44:0) after 1m0s
[23:01:55.698] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:44:0)
[23:01:55.698] Timer tick, asking Buildbucket for the build status
[23:01:55.857] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:55.857] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:45:0) after 1m0s
[23:02:55.965] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:45:0)
[23:02:55.965] Timer tick, asking Buildbucket for the build status
[23:02:56.166] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:56.166] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:46:0) after 1m0s
[23:03:56.363] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:46:0)
[23:03:56.363] Timer tick, asking Buildbucket for the build status
[23:03:56.449] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:56.449] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:47:0) after 1m0s
[23:04:56.504] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:47:0)
[23:04:56.504] Timer tick, asking Buildbucket for the build status
[23:04:56.589] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:56.589] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:48:0) after 1m0s
[23:05:56.648] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:48:0)
[23:05:56.648] Timer tick, asking Buildbucket for the build status
[23:05:56.738] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:56.738] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:49:0) after 1m0s
[23:06:56.799] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:49:0)
[23:06:56.799] Timer tick, asking Buildbucket for the build status
[23:06:57.001] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:57.001] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:50:0) after 1m0s
[23:07:57.118] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:50:0)
[23:07:57.118] Timer tick, asking Buildbucket for the build status
[23:07:57.382] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:57.382] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:51:0) after 1m0s
[23:08:57.427] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:51:0)
[23:08:57.427] Timer tick, asking Buildbucket for the build status
[23:08:57.593] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:57.593] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:52:0) after 1m0s
[23:09:57.688] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:52:0)
[23:09:57.688] Timer tick, asking Buildbucket for the build status
[23:09:58.042] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:58.042] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:53:0) after 1m0s
[23:10:58.134] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:53:0)
[23:10:58.134] Timer tick, asking Buildbucket for the build status
[23:10:58.225] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:58.225] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:54:0) after 1m0s
[23:11:58.293] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:54:0)
[23:11:58.293] Timer tick, asking Buildbucket for the build status
[23:11:58.451] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:58.451] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:55:0) after 1m0s
[23:12:58.551] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:55:0)
[23:12:58.551] Timer tick, asking Buildbucket for the build status
[23:12:58.702] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:58.702] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:56:0) after 1m0s
[23:13:58.744] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:56:0)
[23:13:58.744] Timer tick, asking Buildbucket for the build status
[23:13:58.951] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:58.951] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:57:0) after 1m0s
[23:14:58.980] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:57:0)
[23:14:58.980] Timer tick, asking Buildbucket for the build status
[23:14:59.079] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:59.079] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:58:0) after 1m0s
[23:15:59.190] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:58:0)
[23:15:59.190] Timer tick, asking Buildbucket for the build status
[23:15:59.302] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:59.302] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:59:0) after 1m0s
[23:16:59.345] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:59:0)
[23:16:59.345] Timer tick, asking Buildbucket for the build status
[23:16:59.472] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:59.472] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:60:0) after 1m0s
[23:17:59.495] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:60:0)
[23:17:59.495] Timer tick, asking Buildbucket for the build status
[23:17:59.661] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:59.661] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:61:0) after 1m0s
[23:18:59.745] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:61:0)
[23:18:59.745] Timer tick, asking Buildbucket for the build status
[23:18:59.841] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:59.841] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:62:0) after 1m0s
[23:19:59.873] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:62:0)
[23:19:59.873] Timer tick, asking Buildbucket for the build status
[23:20:00.220] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:00.220] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:63:0) after 1m0s
[23:21:00.257] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:63:0)
[23:21:00.257] Timer tick, asking Buildbucket for the build status
[23:21:00.409] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:00.409] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:64:0) after 1m0s
[23:22:00.489] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:64:0)
[23:22:00.489] Timer tick, asking Buildbucket for the build status
[23:22:00.603] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:00.603] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:65:0) after 1m0s
[23:23:00.678] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:65:0)
[23:23:00.678] Timer tick, asking Buildbucket for the build status
[23:23:00.978] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:00.978] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:66:0) after 1m0s
[23:24:01.008] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:66:0)
[23:24:01.008] Timer tick, asking Buildbucket for the build status
[23:24:01.174] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:01.174] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:67:0) after 1m0s
[23:25:01.285] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:67:0)
[23:25:01.285] Timer tick, asking Buildbucket for the build status
[23:25:01.419] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:01.419] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:68:0) after 1m0s
[23:26:01.448] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:68:0)
[23:26:01.448] Timer tick, asking Buildbucket for the build status
[23:26:01.571] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:01.571] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:69:0) after 1m0s
[23:27:01.623] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:69:0)
[23:27:01.623] Timer tick, asking Buildbucket for the build status
[23:27:01.745] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:01.746] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:70:0) after 1m0s
[23:28:01.796] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:70:0)
[23:28:01.796] Timer tick, asking Buildbucket for the build status
[23:28:01.998] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:01.998] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:71:0) after 1m0s
[23:29:02.068] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:71:0)
[23:29:02.068] Timer tick, asking Buildbucket for the build status
[23:29:02.192] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:02.192] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:72:0) after 1m0s
[23:30:02.270] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:72:0)
[23:30:02.270] Timer tick, asking Buildbucket for the build status
[23:30:02.378] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:02.378] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:73:0) after 1m0s
[23:31:02.434] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:73:0)
[23:31:02.434] Timer tick, asking Buildbucket for the build status
[23:31:02.616] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:02.616] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:74:0) after 1m0s
[23:32:02.709] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:74:0)
[23:32:02.709] Timer tick, asking Buildbucket for the build status
[23:32:03.043] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:03.043] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:75:0) after 1m0s
[23:33:03.106] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:75:0)
[23:33:03.106] Timer tick, asking Buildbucket for the build status
[23:33:03.335] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:03.335] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:76:0) after 1m0s
[23:34:03.385] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:76:0)
[23:34:03.385] Timer tick, asking Buildbucket for the build status
[23:34:03.503] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:03.503] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:77:0) after 1m0s
[23:35:03.580] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:77:0)
[23:35:03.580] Timer tick, asking Buildbucket for the build status
[23:35:03.800] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:03.800] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:78:0) after 1m0s
[23:36:03.892] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:78:0)
[23:36:03.892] Timer tick, asking Buildbucket for the build status
[23:36:03.985] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:03.985] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:79:0) after 1m0s
[23:37:04.054] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:79:0)
[23:37:04.054] Timer tick, asking Buildbucket for the build status
[23:37:04.137] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:04.137] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:80:0) after 1m0s
[23:38:04.179] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:80:0)
[23:38:04.179] Timer tick, asking Buildbucket for the build status
[23:38:04.352] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:04.352] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:81:0) after 1m0s
[23:39:04.477] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:81:0)
[23:39:04.477] Timer tick, asking Buildbucket for the build status
[23:39:04.670] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:04.670] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:82:0) after 1m0s
[23:40:04.747] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:82:0)
[23:40:04.747] Timer tick, asking Buildbucket for the build status
[23:40:04.847] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:04.847] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:83:0) after 1m0s
[23:41:04.931] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:83:0)
[23:41:04.931] Timer tick, asking Buildbucket for the build status
[23:41:05.046] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:05.046] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:84:0) after 1m0s
[23:42:05.105] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:84:0)
[23:42:05.105] Timer tick, asking Buildbucket for the build status
[23:42:05.236] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:05.236] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:85:0) after 1m0s
[23:43:05.293] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:85:0)
[23:43:05.293] Timer tick, asking Buildbucket for the build status
[23:43:05.396] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:05.396] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:86:0) after 1m0s
[23:44:05.495] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:86:0)
[23:44:05.495] Timer tick, asking Buildbucket for the build status
[23:44:05.593] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:05.593] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:87:0) after 1m0s
[23:45:05.629] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:87:0)
[23:45:05.629] Timer tick, asking Buildbucket for the build status
[23:45:05.733] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:05.733] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:88:0) after 1m0s
[23:46:05.756] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:88:0)
[23:46:05.756] Timer tick, asking Buildbucket for the build status
[23:46:05.856] Build 8859631588538897712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:05.856] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:89:0) after 1m0s
[23:46:36.388] Received PubSub notification, asking Buildbucket for the build status
[23:46:36.538] Build 8859631588538897712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:05.879] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:89:0)
[23:47:05.879] Timer tick, asking Buildbucket for the build status
[23:47:05.966] Build 8859631588538897712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:05.966] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:91:0) after 1m0s
[23:48:06.049] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:91:0)
[23:48:06.049] Timer tick, asking Buildbucket for the build status
[23:48:06.144] Build 8859631588538897712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:06.144] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:92:0) after 1m0s
[23:49:06.214] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:92:0)
[23:49:06.214] Timer tick, asking Buildbucket for the build status
[23:49:06.336] Build 8859631588538897712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:06.336] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:93:0) after 1m0s
[23:50:06.362] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:93:0)
[23:50:06.362] Timer tick, asking Buildbucket for the build status
[23:50:06.459] Build 8859631588538897712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:06.459] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:94:0) after 1m0s
[23:51:06.551] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:94:0)
[23:51:06.551] Timer tick, asking Buildbucket for the build status
[23:51:06.664] Build 8859631588538897712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:06.664] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:95:0) after 1m0s
[23:52:06.692] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:95:0)
[23:52:06.692] Timer tick, asking Buildbucket for the build status
[23:52:06.785] Build 8859631588538897712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:06.785] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:96:0) after 1m0s
[23:53:06.811] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:96:0)
[23:53:06.811] Timer tick, asking Buildbucket for the build status
[23:53:06.949] Build 8859631588538897712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:06.949] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:97:0) after 1m0s
[23:54:07.050] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:97:0)
[23:54:07.050] Timer tick, asking Buildbucket for the build status
[23:54:07.159] Build 8859631588538897712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:07.159] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:98:0) after 1m0s
[23:55:07.186] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:98:0)
[23:55:07.186] Timer tick, asking Buildbucket for the build status
[23:55:07.278] Build 8859631588538897712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:07.278] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:99:0) after 1m0s
[23:56:07.395] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:99:0)
[23:56:07.395] Timer tick, asking Buildbucket for the build status
[23:56:07.535] Build 8859631588538897712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:07.535] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:100:0) after 1m0s
[23:57:07.661] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:100:0)
[23:57:07.661] Timer tick, asking Buildbucket for the build status
[23:57:07.750] Build 8859631588538897712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:07.750] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025061650590973376:101:0) after 1m0s
[23:57:34.679] Received PubSub notification, asking Buildbucket for the build status
[23:57:34.758] Build 8859631588538897712: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:57:34.758] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1609199853018706",
"created_by": "project:infra",
"created_ts": "1609193923396675",
"id": "8859631588538897712",
"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\": \"75b40d57094df8c365dec939a30c6eb1b937493b\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@75b40d57094df8c365dec939a30c6eb1b937493b\", \"title\": \"75b40d57094df8c365dec939a30c6eb1b937493b\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/75b40d57094df8c365dec939a30c6eb1b937493b\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"36b482f47d9c4d969cfb643a798d63c03932b4e6\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@36b482f47d9c4d969cfb643a798d63c03932b4e6\", \"title\": \"36b482f47d9c4d969cfb643a798d63c03932b4e6\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/36b482f47d9c4d969cfb643a798d63c03932b4e6\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"81ae84553bd497b21816623138d436e95639e4e2\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@81ae84553bd497b21816623138d436e95639e4e2\", \"title\": \"81ae84553bd497b21816623138d436e95639e4e2\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/81ae84553bd497b21816623138d436e95639e4e2\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"e5516396ad19deaa3ac82e3106f0641255706e2e\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@e5516396ad19deaa3ac82e3106f0641255706e2e\", \"title\": \"e5516396ad19deaa3ac82e3106f0641255706e2e\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/e5516396ad19deaa3ac82e3106f0641255706e2e\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00\", \"title\": \"bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00\", \"got_revision_cp\": \"refs/heads/master@{#37235}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_044445a3bc7a4bfe795493d7956aa02cd526172de1fa7300f69fe152e82ea631_v2\", \"builder_5de1032f0e9ef497433aad28840ee258c037976023b410f653133cee0ad04e2c_v2\", \"builder_82ed111fed7ea8169e482f00b5f3e5338a65daa41208585de67cb0a736b1d538_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_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-awwv\"], \"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\": [\"5657-0417a3f\"], \"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": "1609199192356719",
"status": "COMPLETED",
"status_changed_ts": "1609199853445558",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18221",
"builder:infra-continuous-win10-64",
"buildset:commit/git/bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/bcb68f8bb8673a8ddb7c7d5cc3fb39afab1dbe00",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025061650590973376",
"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/8859631588538897712/+/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:50c4402f852f5710",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1609199853476788",
"url": "https://luci-milo-dev.appspot.com/b/8859631588538897712",
"utcnow_ts": "1609199854751391"
}
}
[23:57:34.758] Invocation finished in 1h38m52.70175207s with status SUCCEEDED