[02:43:20.163] New invocation is queued and will start shortly
[02:43:21.291] Starting the invocation (attempt 1)
[02:43:21.357] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[02:43:21.357] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[02:43:21.357] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9025679179970476160",
"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\":\"7491ce4595a2c639a4d552077f74d67f02574ecc\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@7491ce4595a2c639a4d552077f74d67f02574ecc\",\"title\":\"7491ce4595a2c639a4d552077f74d67f02574ecc\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/7491ce4595a2c639a4d552077f74d67f02574ecc\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"34c74febe4ab33f3feee4a8eab848d0e44cfd2aa\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@34c74febe4ab33f3feee4a8eab848d0e44cfd2aa\",\"title\":\"34c74febe4ab33f3feee4a8eab848d0e44cfd2aa\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/34c74febe4ab33f3feee4a8eab848d0e44cfd2aa\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"34c74febe4ab33f3feee4a8eab848d0e44cfd2aa\"}}",
"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:9025679179970476160",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/34c74febe4ab33f3feee4a8eab848d0e44cfd2aa",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/34c74febe4ab33f3feee4a8eab848d0e44cfd2aa",
"gitiles_ref:refs/heads/master"
]
}
[02:43:21.962] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:infra",
"created_ts": "1608605001452722",
"id": "8860249117955586064",
"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\": \"7491ce4595a2c639a4d552077f74d67f02574ecc\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@7491ce4595a2c639a4d552077f74d67f02574ecc\", \"title\": \"7491ce4595a2c639a4d552077f74d67f02574ecc\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/7491ce4595a2c639a4d552077f74d67f02574ecc\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"34c74febe4ab33f3feee4a8eab848d0e44cfd2aa\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@34c74febe4ab33f3feee4a8eab848d0e44cfd2aa\", \"title\": \"34c74febe4ab33f3feee4a8eab848d0e44cfd2aa\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/34c74febe4ab33f3feee4a8eab848d0e44cfd2aa\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"34c74febe4ab33f3feee4a8eab848d0e44cfd2aa\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608605001842975",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18200",
"builder:infra-continuous-win10-64",
"buildset:commit/git/34c74febe4ab33f3feee4a8eab848d0e44cfd2aa",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/34c74febe4ab33f3feee4a8eab848d0e44cfd2aa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025679179970476160",
"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/8860249117955586064/+/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": "1608605001843156",
"url": "https://luci-milo-dev.appspot.com/b/8860249117955586064",
"utcnow_ts": "1608605001953420"
}
}
[02:43:21.962] Task URL: https://luci-milo-dev.appspot.com/b/8860249117955586064
[02:43:21.962] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:2:0) after 1m0s
[02:44:21.988] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:2:0)
[02:44:21.988] Timer tick, asking Buildbucket for the build status
[02:44:22.086] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:44:22.086] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:3:0) after 1m0s
[02:45:22.180] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:3:0)
[02:45:22.180] Timer tick, asking Buildbucket for the build status
[02:45:22.265] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:45:22.265] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:4:0) after 1m0s
[02:46:22.283] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:4:0)
[02:46:22.283] Timer tick, asking Buildbucket for the build status
[02:46:22.378] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:46:22.378] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:5:0) after 1m0s
[02:47:22.491] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:5:0)
[02:47:22.491] Timer tick, asking Buildbucket for the build status
[02:47:22.579] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:47:22.579] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:6:0) after 1m0s
[02:48:22.597] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:6:0)
[02:48:22.597] Timer tick, asking Buildbucket for the build status
[02:48:22.689] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:48:22.689] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:7:0) after 1m0s
[02:49:22.791] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:7:0)
[02:49:22.791] Timer tick, asking Buildbucket for the build status
[02:49:22.888] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:49:22.888] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:8:0) after 1m0s
[02:50:22.974] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:8:0)
[02:50:22.974] Timer tick, asking Buildbucket for the build status
[02:50:23.116] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:50:23.116] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:9:0) after 1m0s
[02:51:23.185] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:9:0)
[02:51:23.185] Timer tick, asking Buildbucket for the build status
[02:51:23.282] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:51:23.282] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:10:0) after 1m0s
[02:52:23.309] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:10:0)
[02:52:23.309] Timer tick, asking Buildbucket for the build status
[02:52:23.395] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:52:23.395] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:11:0) after 1m0s
[02:53:23.482] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:11:0)
[02:53:23.482] Timer tick, asking Buildbucket for the build status
[02:53:23.559] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:53:23.559] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:12:0) after 1m0s
[02:54:23.578] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:12:0)
[02:54:23.578] Timer tick, asking Buildbucket for the build status
[02:54:23.668] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:54:23.668] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:13:0) after 1m0s
[02:55:23.686] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:13:0)
[02:55:23.686] Timer tick, asking Buildbucket for the build status
[02:55:23.766] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:55:23.766] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:14:0) after 1m0s
[02:56:23.785] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:14:0)
[02:56:23.785] Timer tick, asking Buildbucket for the build status
[02:56:23.868] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:56:23.868] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:15:0) after 1m0s
[02:57:23.885] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:15:0)
[02:57:23.886] Timer tick, asking Buildbucket for the build status
[02:57:23.989] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:57:23.989] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:16:0) after 1m0s
[02:58:24.008] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:16:0)
[02:58:24.008] Timer tick, asking Buildbucket for the build status
[02:58:24.130] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:58:24.130] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:17:0) after 1m0s
[02:59:24.148] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:17:0)
[02:59:24.148] Timer tick, asking Buildbucket for the build status
[02:59:24.251] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:59:24.251] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:18:0) after 1m0s
[03:00:24.270] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:18:0)
[03:00:24.270] Timer tick, asking Buildbucket for the build status
[03:00:24.394] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:00:24.394] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:19:0) after 1m0s
[03:01:24.419] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:19:0)
[03:01:24.419] Timer tick, asking Buildbucket for the build status
[03:01:24.496] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:01:24.496] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:20:0) after 1m0s
[03:02:24.581] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:20:0)
[03:02:24.581] Timer tick, asking Buildbucket for the build status
[03:02:24.760] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:02:24.760] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:21:0) after 1m0s
[03:03:24.780] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:21:0)
[03:03:24.780] Timer tick, asking Buildbucket for the build status
[03:03:24.877] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:03:24.877] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:22:0) after 1m0s
[03:04:24.903] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:22:0)
[03:04:24.903] Timer tick, asking Buildbucket for the build status
[03:04:24.980] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:04:24.980] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:23:0) after 1m0s
[03:05:25.084] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:23:0)
[03:05:25.084] Timer tick, asking Buildbucket for the build status
[03:05:25.166] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:05:25.166] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:24:0) after 1m0s
[03:06:25.191] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:24:0)
[03:06:25.191] Timer tick, asking Buildbucket for the build status
[03:06:25.291] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:06:25.291] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:25:0) after 1m0s
[03:07:25.321] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:25:0)
[03:07:25.321] Timer tick, asking Buildbucket for the build status
[03:07:25.406] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:07:25.406] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:26:0) after 1m0s
[03:08:25.432] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:26:0)
[03:08:25.432] Timer tick, asking Buildbucket for the build status
[03:08:25.551] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:08:25.551] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:27:0) after 1m0s
[03:09:25.574] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:27:0)
[03:09:25.574] Timer tick, asking Buildbucket for the build status
[03:09:25.665] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:09:25.665] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:28:0) after 1m0s
[03:10:25.706] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:28:0)
[03:10:25.706] Timer tick, asking Buildbucket for the build status
[03:10:25.872] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:10:25.872] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:29:0) after 1m0s
[03:11:25.982] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:29:0)
[03:11:25.982] Timer tick, asking Buildbucket for the build status
[03:11:26.990] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:11:26.990] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:30:0) after 1m0s
[03:12:27.093] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:30:0)
[03:12:27.093] Timer tick, asking Buildbucket for the build status
[03:12:27.165] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:12:27.165] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:31:0) after 1m0s
[03:13:27.191] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:31:0)
[03:13:27.191] Timer tick, asking Buildbucket for the build status
[03:13:27.272] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:13:27.272] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:32:0) after 1m0s
[03:14:27.293] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:32:0)
[03:14:27.293] Timer tick, asking Buildbucket for the build status
[03:14:27.363] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:14:27.363] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:33:0) after 1m0s
[03:15:27.382] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:33:0)
[03:15:27.382] Timer tick, asking Buildbucket for the build status
[03:15:27.529] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:15:27.530] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:34:0) after 1m0s
[03:16:27.577] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:34:0)
[03:16:27.577] Timer tick, asking Buildbucket for the build status
[03:16:27.659] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:16:27.659] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:35:0) after 1m0s
[03:17:27.685] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:35:0)
[03:17:27.685] Timer tick, asking Buildbucket for the build status
[03:17:27.831] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:17:27.831] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:36:0) after 1m0s
[03:18:27.857] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:36:0)
[03:18:27.857] Timer tick, asking Buildbucket for the build status
[03:18:27.942] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:18:27.942] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:37:0) after 1m0s
[03:19:27.983] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:37:0)
[03:19:27.983] Timer tick, asking Buildbucket for the build status
[03:19:28.063] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:19:28.063] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:38:0) after 1m0s
[03:20:28.091] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:38:0)
[03:20:28.091] Timer tick, asking Buildbucket for the build status
[03:20:28.204] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:20:28.204] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:39:0) after 1m0s
[03:21:28.292] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:39:0)
[03:21:28.292] Timer tick, asking Buildbucket for the build status
[03:21:28.480] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:21:28.480] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:40:0) after 1m0s
[03:22:28.501] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:40:0)
[03:22:28.501] Timer tick, asking Buildbucket for the build status
[03:22:28.673] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:22:28.673] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:41:0) after 1m0s
[03:23:28.692] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:41:0)
[03:23:28.692] Timer tick, asking Buildbucket for the build status
[03:23:28.777] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:23:28.777] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:42:0) after 1m0s
[03:24:28.878] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:42:0)
[03:24:28.878] Timer tick, asking Buildbucket for the build status
[03:24:28.985] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:24:28.985] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:43:0) after 1m0s
[03:25:29.086] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:43:0)
[03:25:29.086] Timer tick, asking Buildbucket for the build status
[03:25:29.177] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:25:29.177] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:44:0) after 1m0s
[03:26:29.282] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:44:0)
[03:26:29.282] Timer tick, asking Buildbucket for the build status
[03:26:29.379] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:26:29.379] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:45:0) after 1m0s
[03:27:29.494] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:45:0)
[03:27:29.494] Timer tick, asking Buildbucket for the build status
[03:27:29.583] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:27:29.583] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:46:0) after 1m0s
[03:28:29.694] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:46:0)
[03:28:29.694] Timer tick, asking Buildbucket for the build status
[03:28:29.854] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:28:29.854] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:47:0) after 1m0s
[03:29:29.899] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:47:0)
[03:29:29.899] Timer tick, asking Buildbucket for the build status
[03:29:30.032] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:29:30.032] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:48:0) after 1m0s
[03:30:30.092] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:48:0)
[03:30:30.092] Timer tick, asking Buildbucket for the build status
[03:30:30.187] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:30:30.187] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:49:0) after 1m0s
[03:31:30.297] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:49:0)
[03:31:30.297] Timer tick, asking Buildbucket for the build status
[03:31:30.381] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:31:30.381] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:50:0) after 1m0s
[03:32:30.404] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:50:0)
[03:32:30.404] Timer tick, asking Buildbucket for the build status
[03:32:30.489] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:32:30.489] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:51:0) after 1m0s
[03:33:30.579] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:51:0)
[03:33:30.579] Timer tick, asking Buildbucket for the build status
[03:33:30.736] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:33:30.736] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:52:0) after 1m0s
[03:34:30.784] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:52:0)
[03:34:30.784] Timer tick, asking Buildbucket for the build status
[03:34:30.868] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:34:30.868] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:53:0) after 1m0s
[03:35:30.894] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:53:0)
[03:35:30.894] Timer tick, asking Buildbucket for the build status
[03:35:30.964] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:35:30.964] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:54:0) after 1m0s
[03:36:30.998] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:54:0)
[03:36:30.998] Timer tick, asking Buildbucket for the build status
[03:36:31.115] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:36:31.115] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:55:0) after 1m0s
[03:37:31.146] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:55:0)
[03:37:31.146] Timer tick, asking Buildbucket for the build status
[03:37:31.244] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:37:31.244] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:56:0) after 1m0s
[03:38:31.299] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:56:0)
[03:38:31.299] Timer tick, asking Buildbucket for the build status
[03:38:31.395] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:38:31.395] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:57:0) after 1m0s
[03:39:31.415] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:57:0)
[03:39:31.415] Timer tick, asking Buildbucket for the build status
[03:39:31.491] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:39:31.491] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:58:0) after 1m0s
[03:40:31.517] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:58:0)
[03:40:31.517] Timer tick, asking Buildbucket for the build status
[03:40:31.610] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:40:31.610] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:59:0) after 1m0s
[03:41:31.683] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:59:0)
[03:41:31.683] Timer tick, asking Buildbucket for the build status
[03:41:31.775] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:41:31.775] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:60:0) after 1m0s
[03:42:31.899] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:60:0)
[03:42:31.899] Timer tick, asking Buildbucket for the build status
[03:42:31.985] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:42:31.985] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:61:0) after 1m0s
[03:43:32.007] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:61:0)
[03:43:32.007] Timer tick, asking Buildbucket for the build status
[03:43:32.122] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:43:32.122] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:62:0) after 1m0s
[03:44:32.196] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:62:0)
[03:44:32.196] Timer tick, asking Buildbucket for the build status
[03:44:32.272] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:44:32.272] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:63:0) after 1m0s
[03:45:32.302] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:63:0)
[03:45:32.302] Timer tick, asking Buildbucket for the build status
[03:45:32.450] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:45:32.450] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:64:0) after 1m0s
[03:46:32.496] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:64:0)
[03:46:32.496] Timer tick, asking Buildbucket for the build status
[03:46:32.600] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:46:32.600] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:65:0) after 1m0s
[03:47:32.695] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:65:0)
[03:47:32.695] Timer tick, asking Buildbucket for the build status
[03:47:32.841] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:47:32.841] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:66:0) after 1m0s
[03:48:32.889] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:66:0)
[03:48:32.889] Timer tick, asking Buildbucket for the build status
[03:48:32.981] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:48:32.981] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:67:0) after 1m0s
[03:49:33.076] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:67:0)
[03:49:33.076] Timer tick, asking Buildbucket for the build status
[03:49:33.152] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:49:33.152] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:68:0) after 1m0s
[03:50:33.181] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:68:0)
[03:50:33.181] Timer tick, asking Buildbucket for the build status
[03:50:33.283] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:50:33.283] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:69:0) after 1m0s
[03:51:33.391] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:69:0)
[03:51:33.391] Timer tick, asking Buildbucket for the build status
[03:51:33.513] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:51:33.513] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:70:0) after 1m0s
[03:52:33.593] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:70:0)
[03:52:33.593] Timer tick, asking Buildbucket for the build status
[03:52:33.754] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:52:33.754] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:71:0) after 1m0s
[03:53:33.798] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:71:0)
[03:53:33.798] Timer tick, asking Buildbucket for the build status
[03:53:33.869] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:53:33.869] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:72:0) after 1m0s
[03:54:33.978] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:72:0)
[03:54:33.978] Timer tick, asking Buildbucket for the build status
[03:54:34.098] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:54:34.098] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:73:0) after 1m0s
[03:55:34.194] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:73:0)
[03:55:34.194] Timer tick, asking Buildbucket for the build status
[03:55:34.300] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:55:34.300] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:74:0) after 1m0s
[03:56:34.321] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:74:0)
[03:56:34.321] Timer tick, asking Buildbucket for the build status
[03:56:34.397] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:56:34.397] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:75:0) after 1m0s
[03:57:34.493] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:75:0)
[03:57:34.493] Timer tick, asking Buildbucket for the build status
[03:57:34.563] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:57:34.563] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:76:0) after 1m0s
[03:58:34.590] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:76:0)
[03:58:34.590] Timer tick, asking Buildbucket for the build status
[03:58:34.661] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:58:34.661] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:77:0) after 1m0s
[03:59:34.694] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:77:0)
[03:59:34.694] Timer tick, asking Buildbucket for the build status
[03:59:34.814] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:59:34.814] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:78:0) after 1m0s
[04:00:34.882] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:78:0)
[04:00:34.882] Timer tick, asking Buildbucket for the build status
[04:00:35.022] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:00:35.022] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:79:0) after 1m0s
[04:01:35.204] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:79:0)
[04:01:35.204] Timer tick, asking Buildbucket for the build status
[04:01:35.327] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:01:35.327] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:80:0) after 1m0s
[04:02:35.349] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:80:0)
[04:02:35.349] Timer tick, asking Buildbucket for the build status
[04:02:35.437] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:02:35.437] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:81:0) after 1m0s
[04:03:35.493] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:81:0)
[04:03:35.493] Timer tick, asking Buildbucket for the build status
[04:03:35.572] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:03:35.572] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:82:0) after 1m0s
[04:04:35.602] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:82:0)
[04:04:35.602] Timer tick, asking Buildbucket for the build status
[04:04:35.669] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:04:35.669] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:83:0) after 1m0s
[04:05:35.732] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:83:0)
[04:05:35.732] Timer tick, asking Buildbucket for the build status
[04:05:35.817] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:05:35.817] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:84:0) after 1m0s
[04:06:35.986] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:84:0)
[04:06:35.986] Timer tick, asking Buildbucket for the build status
[04:06:36.110] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:06:36.110] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:85:0) after 1m0s
[04:07:36.267] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:85:0)
[04:07:36.267] Timer tick, asking Buildbucket for the build status
[04:07:36.579] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:07:36.579] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:86:0) after 1m0s
[04:08:36.603] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:86:0)
[04:08:36.603] Timer tick, asking Buildbucket for the build status
[04:08:36.861] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:08:36.861] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:87:0) after 1m0s
[04:09:36.883] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:87:0)
[04:09:36.883] Timer tick, asking Buildbucket for the build status
[04:09:37.370] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:09:37.370] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:88:0) after 1m0s
[04:10:37.530] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:88:0)
[04:10:37.530] Timer tick, asking Buildbucket for the build status
[04:10:37.614] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:10:37.614] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:89:0) after 1m0s
[04:11:37.704] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:89:0)
[04:11:37.704] Timer tick, asking Buildbucket for the build status
[04:11:37.994] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:11:37.994] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:90:0) after 1m0s
[04:12:38.091] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:90:0)
[04:12:38.091] Timer tick, asking Buildbucket for the build status
[04:12:39.484] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:12:39.484] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:91:0) after 1m0s
[04:13:39.509] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:91:0)
[04:13:39.509] Timer tick, asking Buildbucket for the build status
[04:13:39.754] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:13:39.754] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:92:0) after 1m0s
[04:14:39.776] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:92:0)
[04:14:39.776] Timer tick, asking Buildbucket for the build status
[04:14:40.046] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:14:40.046] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:93:0) after 1m0s
[04:15:40.084] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:93:0)
[04:15:40.084] Timer tick, asking Buildbucket for the build status
[04:15:40.435] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:15:40.435] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:94:0) after 1m0s
[04:16:40.459] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:94:0)
[04:16:40.459] Timer tick, asking Buildbucket for the build status
[04:16:40.674] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:16:40.674] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:95:0) after 1m0s
[04:17:40.698] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:95:0)
[04:17:40.698] Timer tick, asking Buildbucket for the build status
[04:17:40.934] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:17:40.934] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:96:0) after 1m0s
[04:18:40.979] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:96:0)
[04:18:40.979] Timer tick, asking Buildbucket for the build status
[04:18:41.291] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:18:41.291] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:97:0) after 1m0s
[04:19:41.391] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:97:0)
[04:19:41.391] Timer tick, asking Buildbucket for the build status
[04:19:41.667] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:19:41.667] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:98:0) after 1m0s
[04:20:41.691] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:98:0)
[04:20:41.691] Timer tick, asking Buildbucket for the build status
[04:20:41.872] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:20:41.872] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:99:0) after 1m0s
[04:21:41.902] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:99:0)
[04:21:41.902] Timer tick, asking Buildbucket for the build status
[04:21:42.244] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:21:42.244] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:100:0) after 1m0s
[04:22:42.273] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:100:0)
[04:22:42.273] Timer tick, asking Buildbucket for the build status
[04:22:42.517] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:22:42.517] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:101:0) after 1m0s
[04:23:42.578] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:101:0)
[04:23:42.578] Timer tick, asking Buildbucket for the build status
[04:23:42.907] Build 8860249117955586064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:23:42.907] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:102:0) after 1m0s
[04:24:37.578] Received PubSub notification, asking Buildbucket for the build status
[04:24:37.662] Build 8860249117955586064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:42.983] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:102:0)
[04:24:42.983] Timer tick, asking Buildbucket for the build status
[04:24:43.196] Build 8860249117955586064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:43.196] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:104:0) after 1m0s
[04:25:43.224] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:104:0)
[04:25:43.224] Timer tick, asking Buildbucket for the build status
[04:25:43.519] Build 8860249117955586064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:43.519] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:105:0) after 1m0s
[04:26:43.578] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:105:0)
[04:26:43.578] Timer tick, asking Buildbucket for the build status
[04:26:43.980] Build 8860249117955586064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:43.980] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:106:0) after 1m0s
[04:27:44.098] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:106:0)
[04:27:44.098] Timer tick, asking Buildbucket for the build status
[04:27:44.413] Build 8860249117955586064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:44.413] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:107:0) after 1m0s
[04:28:44.498] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:107:0)
[04:28:44.498] Timer tick, asking Buildbucket for the build status
[04:28:44.818] Build 8860249117955586064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:44.818] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:108:0) after 1m0s
[04:29:44.898] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:108:0)
[04:29:44.898] Timer tick, asking Buildbucket for the build status
[04:29:45.110] Build 8860249117955586064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:45.110] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:109:0) after 1m0s
[04:30:45.134] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:109:0)
[04:30:45.134] Timer tick, asking Buildbucket for the build status
[04:30:45.533] Build 8860249117955586064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:45.533] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9025679179970476160:110:0) after 1m0s
[04:31:07.893] Received PubSub notification, asking Buildbucket for the build status
[04:31:08.038] Build 8860249117955586064: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:31:08.038] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1608611444164973",
"created_by": "project:infra",
"created_ts": "1608605001452722",
"id": "8860249117955586064",
"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\": \"7491ce4595a2c639a4d552077f74d67f02574ecc\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@7491ce4595a2c639a4d552077f74d67f02574ecc\", \"title\": \"7491ce4595a2c639a4d552077f74d67f02574ecc\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/7491ce4595a2c639a4d552077f74d67f02574ecc\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"34c74febe4ab33f3feee4a8eab848d0e44cfd2aa\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@34c74febe4ab33f3feee4a8eab848d0e44cfd2aa\", \"title\": \"34c74febe4ab33f3feee4a8eab848d0e44cfd2aa\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/34c74febe4ab33f3feee4a8eab848d0e44cfd2aa\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"34c74febe4ab33f3feee4a8eab848d0e44cfd2aa\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"34c74febe4ab33f3feee4a8eab848d0e44cfd2aa\", \"got_revision_cp\": \"refs/heads/master@{#37191}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_044445a3bc7a4bfe795493d7956aa02cd526172de1fa7300f69fe152e82ea631_v2\", \"builder_5de1032f0e9ef497433aad28840ee258c037976023b410f653133cee0ad04e2c_v2\", \"builder_82ed111fed7ea8169e482f00b5f3e5338a65daa41208585de67cb0a736b1d538_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"builder_d38bdc976f893fc6930479dce8a6d7dc793b2a74fe1392d4af00713a5c0bb46e_v2\", \"git\", \"goma\", \"infra_gclient_with_go\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-ea9m\"], \"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": "1608611074207392",
"status": "COMPLETED",
"status_changed_ts": "1608611466610727",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18200",
"builder:infra-continuous-win10-64",
"buildset:commit/git/34c74febe4ab33f3feee4a8eab848d0e44cfd2aa",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/34c74febe4ab33f3feee4a8eab848d0e44cfd2aa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025679179970476160",
"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/8860249117955586064/+/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:50a125f153a33610",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1608611466627168",
"url": "https://luci-milo-dev.appspot.com/b/8860249117955586064",
"utcnow_ts": "1608611468025839"
}
}
[04:31:08.038] Invocation finished in 1h47m47.891677561s with status SUCCEEDED