[08:47:14.124] New invocation is queued and will start shortly
[08:47:15.270] Starting the invocation (attempt 1)
[08:47:15.322] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[08:47:15.323] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[08:47:15.324] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9019858079553338032",
"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\":\"663b7a3715493ef75087e20fe80df1d33373b2c2\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@663b7a3715493ef75087e20fe80df1d33373b2c2\",\"title\":\"663b7a3715493ef75087e20fe80df1d33373b2c2\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/663b7a3715493ef75087e20fe80df1d33373b2c2\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"663b7a3715493ef75087e20fe80df1d33373b2c2\"}}",
"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:9019858079553338032",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/663b7a3715493ef75087e20fe80df1d33373b2c2",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/663b7a3715493ef75087e20fe80df1d33373b2c2",
"gitiles_ref:refs/heads/master"
]
}
[08:47:16.204] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1614156435446605",
"id": "8854428017504071248",
"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\": \"663b7a3715493ef75087e20fe80df1d33373b2c2\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@663b7a3715493ef75087e20fe80df1d33373b2c2\", \"title\": \"663b7a3715493ef75087e20fe80df1d33373b2c2\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/663b7a3715493ef75087e20fe80df1d33373b2c2\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"663b7a3715493ef75087e20fe80df1d33373b2c2\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614156436081443",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18694",
"builder:infra-continuous-win10-64",
"buildset:commit/git/663b7a3715493ef75087e20fe80df1d33373b2c2",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/663b7a3715493ef75087e20fe80df1d33373b2c2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019858079553338032",
"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/8854428017504071248/+/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": "1614156436081623",
"url": "https://luci-milo-dev.appspot.com/b/8854428017504071248",
"utcnow_ts": "1614156436197104"
}
}
[08:47:16.204] Task URL: https://luci-milo-dev.appspot.com/b/8854428017504071248
[08:47:16.204] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:2:0) after 1m0s
[08:48:16.248] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:2:0)
[08:48:16.248] Timer tick, asking Buildbucket for the build status
[08:48:16.525] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:48:16.525] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:3:0) after 1m0s
[08:49:16.566] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:3:0)
[08:49:16.566] Timer tick, asking Buildbucket for the build status
[08:49:16.656] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:49:16.656] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:4:0) after 1m0s
[08:50:16.767] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:4:0)
[08:50:16.767] Timer tick, asking Buildbucket for the build status
[08:50:16.847] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:50:16.847] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:5:0) after 1m0s
[08:51:16.875] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:5:0)
[08:51:16.875] Timer tick, asking Buildbucket for the build status
[08:51:16.958] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:51:16.958] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:6:0) after 1m0s
[08:52:17.066] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:6:0)
[08:52:17.066] Timer tick, asking Buildbucket for the build status
[08:52:17.141] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:52:17.141] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:7:0) after 1m0s
[08:53:17.159] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:7:0)
[08:53:17.159] Timer tick, asking Buildbucket for the build status
[08:53:17.236] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:53:17.236] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:8:0) after 1m0s
[08:54:17.264] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:8:0)
[08:54:17.264] Timer tick, asking Buildbucket for the build status
[08:54:17.352] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:54:17.352] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:9:0) after 1m0s
[08:55:17.428] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:9:0)
[08:55:17.428] Timer tick, asking Buildbucket for the build status
[08:55:17.513] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:55:17.513] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:10:0) after 1m0s
[08:56:17.553] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:10:0)
[08:56:17.553] Timer tick, asking Buildbucket for the build status
[08:56:17.636] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:56:17.636] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:11:0) after 1m0s
[08:57:17.678] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:11:0)
[08:57:17.678] Timer tick, asking Buildbucket for the build status
[08:57:17.749] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:57:17.749] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:12:0) after 1m0s
[08:58:17.877] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:12:0)
[08:58:17.877] Timer tick, asking Buildbucket for the build status
[08:58:17.962] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:58:17.962] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:13:0) after 1m0s
[08:59:18.053] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:13:0)
[08:59:18.053] Timer tick, asking Buildbucket for the build status
[08:59:18.150] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:59:18.150] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:14:0) after 1m0s
[09:00:18.174] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:14:0)
[09:00:18.174] Timer tick, asking Buildbucket for the build status
[09:00:18.252] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:00:18.252] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:15:0) after 1m0s
[09:01:18.274] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:15:0)
[09:01:18.274] Timer tick, asking Buildbucket for the build status
[09:01:18.415] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:01:18.415] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:16:0) after 1m0s
[09:02:18.447] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:16:0)
[09:02:18.447] Timer tick, asking Buildbucket for the build status
[09:02:18.522] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:02:18.522] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:17:0) after 1m0s
[09:03:18.543] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:17:0)
[09:03:18.543] Timer tick, asking Buildbucket for the build status
[09:03:18.619] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:03:18.619] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:18:0) after 1m0s
[09:04:18.639] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:18:0)
[09:04:18.639] Timer tick, asking Buildbucket for the build status
[09:04:18.739] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:04:18.739] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:19:0) after 1m0s
[09:05:18.767] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:19:0)
[09:05:18.767] Timer tick, asking Buildbucket for the build status
[09:05:18.897] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:05:18.897] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:20:0) after 1m0s
[09:06:18.953] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:20:0)
[09:06:18.953] Timer tick, asking Buildbucket for the build status
[09:06:19.048] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:06:19.048] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:21:0) after 1m0s
[09:07:19.166] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:21:0)
[09:07:19.166] Timer tick, asking Buildbucket for the build status
[09:07:19.246] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:07:19.246] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:22:0) after 1m0s
[09:08:19.321] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:22:0)
[09:08:19.321] Timer tick, asking Buildbucket for the build status
[09:08:19.420] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:08:19.420] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:23:0) after 1m0s
[09:09:19.460] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:23:0)
[09:09:19.460] Timer tick, asking Buildbucket for the build status
[09:09:19.546] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:09:19.546] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:24:0) after 1m0s
[09:10:19.568] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:24:0)
[09:10:19.568] Timer tick, asking Buildbucket for the build status
[09:10:19.651] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:10:19.651] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:25:0) after 1m0s
[09:11:19.767] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:25:0)
[09:11:19.767] Timer tick, asking Buildbucket for the build status
[09:11:19.876] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:11:19.876] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:26:0) after 1m0s
[09:12:19.973] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:26:0)
[09:12:19.973] Timer tick, asking Buildbucket for the build status
[09:12:20.055] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:12:20.055] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:27:0) after 1m0s
[09:13:20.124] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:27:0)
[09:13:20.124] Timer tick, asking Buildbucket for the build status
[09:13:20.208] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:13:20.208] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:28:0) after 1m0s
[09:14:20.233] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:28:0)
[09:14:20.233] Timer tick, asking Buildbucket for the build status
[09:14:20.304] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:14:20.304] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:29:0) after 1m0s
[09:15:20.341] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:29:0)
[09:15:20.341] Timer tick, asking Buildbucket for the build status
[09:15:20.442] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:15:20.442] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:30:0) after 1m0s
[09:16:20.521] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:30:0)
[09:16:20.521] Timer tick, asking Buildbucket for the build status
[09:16:20.603] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:16:20.603] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:31:0) after 1m0s
[09:17:20.728] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:31:0)
[09:17:20.728] Timer tick, asking Buildbucket for the build status
[09:17:20.876] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:17:20.876] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:32:0) after 1m0s
[09:18:20.939] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:32:0)
[09:18:20.939] Timer tick, asking Buildbucket for the build status
[09:18:21.014] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:18:21.014] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:33:0) after 1m0s
[09:19:21.054] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:33:0)
[09:19:21.054] Timer tick, asking Buildbucket for the build status
[09:19:21.140] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:19:21.140] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:34:0) after 1m0s
[09:20:21.229] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:34:0)
[09:20:21.229] Timer tick, asking Buildbucket for the build status
[09:20:21.304] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:20:21.304] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:35:0) after 1m0s
[09:21:21.325] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:35:0)
[09:21:21.325] Timer tick, asking Buildbucket for the build status
[09:21:21.402] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:21:21.402] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:36:0) after 1m0s
[09:22:21.466] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:36:0)
[09:22:21.466] Timer tick, asking Buildbucket for the build status
[09:22:21.541] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:22:21.542] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:37:0) after 1m0s
[09:23:21.567] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:37:0)
[09:23:21.567] Timer tick, asking Buildbucket for the build status
[09:23:21.648] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:23:21.649] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:38:0) after 1m0s
[09:24:21.676] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:38:0)
[09:24:21.676] Timer tick, asking Buildbucket for the build status
[09:24:21.775] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:24:21.775] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:39:0) after 1m0s
[09:25:21.924] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:39:0)
[09:25:21.924] Timer tick, asking Buildbucket for the build status
[09:25:22.006] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:25:22.006] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:40:0) after 1m0s
[09:26:22.071] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:40:0)
[09:26:22.071] Timer tick, asking Buildbucket for the build status
[09:26:22.166] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:26:22.166] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:41:0) after 1m0s
[09:27:22.183] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:41:0)
[09:27:22.183] Timer tick, asking Buildbucket for the build status
[09:27:22.275] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:27:22.275] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:42:0) after 1m0s
[09:28:22.298] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:42:0)
[09:28:22.298] Timer tick, asking Buildbucket for the build status
[09:28:22.406] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:28:22.406] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:43:0) after 1m0s
[09:29:22.517] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:43:0)
[09:29:22.517] Timer tick, asking Buildbucket for the build status
[09:29:22.609] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:29:22.609] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:44:0) after 1m0s
[09:30:22.685] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:44:0)
[09:30:22.685] Timer tick, asking Buildbucket for the build status
[09:30:22.764] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:30:22.764] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:45:0) after 1m0s
[09:31:22.871] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:45:0)
[09:31:22.871] Timer tick, asking Buildbucket for the build status
[09:31:22.950] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:31:22.950] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:46:0) after 1m0s
[09:32:22.984] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:46:0)
[09:32:22.984] Timer tick, asking Buildbucket for the build status
[09:32:23.084] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:32:23.084] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:47:0) after 1m0s
[09:33:23.172] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:47:0)
[09:33:23.172] Timer tick, asking Buildbucket for the build status
[09:33:23.256] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:33:23.256] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:48:0) after 1m0s
[09:34:23.359] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:48:0)
[09:34:23.359] Timer tick, asking Buildbucket for the build status
[09:34:23.457] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:34:23.457] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:49:0) after 1m0s
[09:35:23.563] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:49:0)
[09:35:23.563] Timer tick, asking Buildbucket for the build status
[09:35:23.640] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:35:23.640] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:50:0) after 1m0s
[09:36:23.668] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:50:0)
[09:36:23.668] Timer tick, asking Buildbucket for the build status
[09:36:23.741] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:36:23.741] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:51:0) after 1m0s
[09:37:23.823] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:51:0)
[09:37:23.823] Timer tick, asking Buildbucket for the build status
[09:37:23.904] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:37:23.904] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:52:0) after 1m0s
[09:38:24.014] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:52:0)
[09:38:24.014] Timer tick, asking Buildbucket for the build status
[09:38:24.160] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:38:24.160] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:53:0) after 1m0s
[09:39:24.226] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:53:0)
[09:39:24.226] Timer tick, asking Buildbucket for the build status
[09:39:24.307] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:39:24.307] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:54:0) after 1m0s
[09:40:24.324] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:54:0)
[09:40:24.324] Timer tick, asking Buildbucket for the build status
[09:40:24.402] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:40:24.402] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:55:0) after 1m0s
[09:41:24.421] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:55:0)
[09:41:24.421] Timer tick, asking Buildbucket for the build status
[09:41:24.497] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:41:24.497] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:56:0) after 1m0s
[09:42:24.518] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:56:0)
[09:42:24.518] Timer tick, asking Buildbucket for the build status
[09:42:24.602] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:42:24.602] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:57:0) after 1m0s
[09:43:24.666] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:57:0)
[09:43:24.666] Timer tick, asking Buildbucket for the build status
[09:43:24.737] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:43:24.737] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:58:0) after 1m0s
[09:44:24.816] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:58:0)
[09:44:24.816] Timer tick, asking Buildbucket for the build status
[09:44:24.895] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:44:24.895] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:59:0) after 1m0s
[09:45:24.918] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:59:0)
[09:45:24.918] Timer tick, asking Buildbucket for the build status
[09:45:25.060] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:45:25.060] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:60:0) after 1m0s
[09:46:25.118] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:60:0)
[09:46:25.118] Timer tick, asking Buildbucket for the build status
[09:46:25.199] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:46:25.200] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:61:0) after 1m0s
[09:47:25.231] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:61:0)
[09:47:25.231] Timer tick, asking Buildbucket for the build status
[09:47:25.343] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:47:25.343] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:62:0) after 1m0s
[09:48:25.367] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:62:0)
[09:48:25.367] Timer tick, asking Buildbucket for the build status
[09:48:25.441] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:48:25.441] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:63:0) after 1m0s
[09:49:25.467] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:63:0)
[09:49:25.467] Timer tick, asking Buildbucket for the build status
[09:49:25.547] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:49:25.547] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:64:0) after 1m0s
[09:50:25.566] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:64:0)
[09:50:25.566] Timer tick, asking Buildbucket for the build status
[09:50:25.644] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:50:25.644] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:65:0) after 1m0s
[09:51:25.665] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:65:0)
[09:51:25.665] Timer tick, asking Buildbucket for the build status
[09:51:25.753] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:51:25.753] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:66:0) after 1m0s
[09:52:25.772] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:66:0)
[09:52:25.772] Timer tick, asking Buildbucket for the build status
[09:52:25.881] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:52:25.881] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:67:0) after 1m0s
[09:53:26.047] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:67:0)
[09:53:26.047] Timer tick, asking Buildbucket for the build status
[09:53:26.116] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:53:26.116] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:68:0) after 1m0s
[09:54:26.178] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:68:0)
[09:54:26.178] Timer tick, asking Buildbucket for the build status
[09:54:26.252] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:54:26.252] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:69:0) after 1m0s
[09:55:26.337] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:69:0)
[09:55:26.337] Timer tick, asking Buildbucket for the build status
[09:55:26.403] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:55:26.403] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:70:0) after 1m0s
[09:56:26.511] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:70:0)
[09:56:26.511] Timer tick, asking Buildbucket for the build status
[09:56:26.579] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:56:26.579] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:71:0) after 1m0s
[09:57:26.659] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:71:0)
[09:57:26.659] Timer tick, asking Buildbucket for the build status
[09:57:26.737] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:57:26.737] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:72:0) after 1m0s
[09:58:26.762] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:72:0)
[09:58:26.762] Timer tick, asking Buildbucket for the build status
[09:58:26.863] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:58:26.863] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:73:0) after 1m0s
[09:59:26.881] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:73:0)
[09:59:26.881] Timer tick, asking Buildbucket for the build status
[09:59:26.975] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:59:26.976] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:74:0) after 1m0s
[10:00:27.137] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:74:0)
[10:00:27.137] Timer tick, asking Buildbucket for the build status
[10:00:27.230] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:00:27.231] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:75:0) after 1m0s
[10:01:27.251] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:75:0)
[10:01:27.251] Timer tick, asking Buildbucket for the build status
[10:01:27.383] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:01:27.383] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:76:0) after 1m0s
[10:02:27.402] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:76:0)
[10:02:27.402] Timer tick, asking Buildbucket for the build status
[10:02:27.471] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:02:27.471] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:77:0) after 1m0s
[10:03:27.536] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:77:0)
[10:03:27.536] Timer tick, asking Buildbucket for the build status
[10:03:27.623] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:03:27.623] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:78:0) after 1m0s
[10:04:27.682] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:78:0)
[10:04:27.682] Timer tick, asking Buildbucket for the build status
[10:04:27.762] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:04:27.762] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:79:0) after 1m0s
[10:05:27.811] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:79:0)
[10:05:27.811] Timer tick, asking Buildbucket for the build status
[10:05:28.147] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:05:28.147] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:80:0) after 1m0s
[10:06:28.172] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:80:0)
[10:06:28.172] Timer tick, asking Buildbucket for the build status
[10:06:28.263] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:06:28.263] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:81:0) after 1m0s
[10:07:28.286] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:81:0)
[10:07:28.286] Timer tick, asking Buildbucket for the build status
[10:07:28.375] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:07:28.375] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:82:0) after 1m0s
[10:08:28.413] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:82:0)
[10:08:28.413] Timer tick, asking Buildbucket for the build status
[10:08:28.494] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:08:28.494] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:83:0) after 1m0s
[10:09:28.557] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:83:0)
[10:09:28.557] Timer tick, asking Buildbucket for the build status
[10:09:28.640] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:09:28.640] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:84:0) after 1m0s
[10:10:28.665] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:84:0)
[10:10:28.665] Timer tick, asking Buildbucket for the build status
[10:10:28.735] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:10:28.735] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:85:0) after 1m0s
[10:11:28.755] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:85:0)
[10:11:28.755] Timer tick, asking Buildbucket for the build status
[10:11:28.831] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:11:28.831] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:86:0) after 1m0s
[10:12:28.849] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:86:0)
[10:12:28.849] Timer tick, asking Buildbucket for the build status
[10:12:28.922] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:12:28.922] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:87:0) after 1m0s
[10:13:28.953] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:87:0)
[10:13:28.953] Timer tick, asking Buildbucket for the build status
[10:13:29.029] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:13:29.029] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:88:0) after 1m0s
[10:14:29.112] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:88:0)
[10:14:29.112] Timer tick, asking Buildbucket for the build status
[10:14:29.190] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:14:29.190] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:89:0) after 1m0s
[10:15:29.221] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:89:0)
[10:15:29.221] Timer tick, asking Buildbucket for the build status
[10:15:29.307] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:15:29.308] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:90:0) after 1m0s
[10:16:29.331] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:90:0)
[10:16:29.331] Timer tick, asking Buildbucket for the build status
[10:16:29.498] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:16:29.498] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:91:0) after 1m0s
[10:17:29.569] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:91:0)
[10:17:29.569] Timer tick, asking Buildbucket for the build status
[10:17:29.652] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:17:29.652] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:92:0) after 1m0s
[10:18:29.720] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:92:0)
[10:18:29.720] Timer tick, asking Buildbucket for the build status
[10:18:29.875] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:18:29.875] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:93:0) after 1m0s
[10:19:29.926] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:93:0)
[10:19:29.926] Timer tick, asking Buildbucket for the build status
[10:19:30.007] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:19:30.007] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:94:0) after 1m0s
[10:20:30.045] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:94:0)
[10:20:30.045] Timer tick, asking Buildbucket for the build status
[10:20:30.120] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:20:30.120] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:95:0) after 1m0s
[10:21:30.216] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:95:0)
[10:21:30.216] Timer tick, asking Buildbucket for the build status
[10:21:30.295] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:21:30.295] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:96:0) after 1m0s
[10:22:30.382] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:96:0)
[10:22:30.382] Timer tick, asking Buildbucket for the build status
[10:22:30.470] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:22:30.470] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:97:0) after 1m0s
[10:23:30.501] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:97:0)
[10:23:30.501] Timer tick, asking Buildbucket for the build status
[10:23:30.580] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:23:30.580] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:98:0) after 1m0s
[10:24:30.603] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:98:0)
[10:24:30.603] Timer tick, asking Buildbucket for the build status
[10:24:30.691] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:24:30.691] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:99:0) after 1m0s
[10:25:30.722] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:99:0)
[10:25:30.722] Timer tick, asking Buildbucket for the build status
[10:25:30.797] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:25:30.797] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:100:0) after 1m0s
[10:26:30.823] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:100:0)
[10:26:30.823] Timer tick, asking Buildbucket for the build status
[10:26:30.973] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:26:30.973] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:101:0) after 1m0s
[10:27:31.070] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:101:0)
[10:27:31.070] Timer tick, asking Buildbucket for the build status
[10:27:31.205] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:27:31.205] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:102:0) after 1m0s
[10:28:31.258] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:102:0)
[10:28:31.258] Timer tick, asking Buildbucket for the build status
[10:28:31.343] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:28:31.343] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:103:0) after 1m0s
[10:29:31.415] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:103:0)
[10:29:31.415] Timer tick, asking Buildbucket for the build status
[10:29:31.491] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:29:31.491] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:104:0) after 1m0s
[10:30:31.525] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:104:0)
[10:30:31.526] Timer tick, asking Buildbucket for the build status
[10:30:31.662] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:30:31.662] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:105:0) after 1m0s
[10:31:31.764] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:105:0)
[10:31:31.764] Timer tick, asking Buildbucket for the build status
[10:31:31.878] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:31:31.878] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:106:0) after 1m0s
[10:32:32.075] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:106:0)
[10:32:32.075] Timer tick, asking Buildbucket for the build status
[10:32:32.164] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:32:32.164] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:107:0) after 1m0s
[10:33:32.190] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:107:0)
[10:33:32.190] Timer tick, asking Buildbucket for the build status
[10:33:32.288] Build 8854428017504071248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:33:32.288] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:108:0) after 1m0s
[10:34:22.066] Received PubSub notification, asking Buildbucket for the build status
[10:34:22.139] Build 8854428017504071248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:32.345] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:108:0)
[10:34:32.345] Timer tick, asking Buildbucket for the build status
[10:34:32.425] Build 8854428017504071248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:32.425] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:110:0) after 1m0s
[10:35:32.462] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:110:0)
[10:35:32.462] Timer tick, asking Buildbucket for the build status
[10:35:32.535] Build 8854428017504071248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:32.535] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:111:0) after 1m0s
[10:36:32.569] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:111:0)
[10:36:32.569] Timer tick, asking Buildbucket for the build status
[10:36:32.654] Build 8854428017504071248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:32.654] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:112:0) after 1m0s
[10:37:32.720] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:112:0)
[10:37:32.720] Timer tick, asking Buildbucket for the build status
[10:37:32.793] Build 8854428017504071248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:32.793] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:113:0) after 1m0s
[10:38:32.826] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:113:0)
[10:38:32.826] Timer tick, asking Buildbucket for the build status
[10:38:32.890] Build 8854428017504071248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:32.890] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:114:0) after 1m0s
[10:39:32.932] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:114:0)
[10:39:32.933] Timer tick, asking Buildbucket for the build status
[10:39:33.045] Build 8854428017504071248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:33.045] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:115:0) after 1m0s
[10:40:33.079] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:115:0)
[10:40:33.079] Timer tick, asking Buildbucket for the build status
[10:40:33.142] Build 8854428017504071248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:33.142] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:116:0) after 1m0s
[10:41:33.174] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:116:0)
[10:41:33.174] Timer tick, asking Buildbucket for the build status
[10:41:33.261] Build 8854428017504071248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:33.261] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:117:0) after 1m0s
[10:42:33.328] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:117:0)
[10:42:33.328] Timer tick, asking Buildbucket for the build status
[10:42:33.416] Build 8854428017504071248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:33.417] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:118:0) after 1m0s
[10:43:33.437] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:118:0)
[10:43:33.437] Timer tick, asking Buildbucket for the build status
[10:43:33.561] Build 8854428017504071248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:33.561] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019858079553338032:119:0) after 1m0s
[10:43:41.239] Received PubSub notification, asking Buildbucket for the build status
[10:43:41.375] Build 8854428017504071248: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:43:41.375] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1614163420785439",
"created_by": "project:infra",
"created_ts": "1614156435446605",
"id": "8854428017504071248",
"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\": \"663b7a3715493ef75087e20fe80df1d33373b2c2\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@663b7a3715493ef75087e20fe80df1d33373b2c2\", \"title\": \"663b7a3715493ef75087e20fe80df1d33373b2c2\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/663b7a3715493ef75087e20fe80df1d33373b2c2\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"663b7a3715493ef75087e20fe80df1d33373b2c2\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"663b7a3715493ef75087e20fe80df1d33373b2c2\", \"got_revision_cp\": \"refs/heads/master@{#38778}\"}, \"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-1h6g\"], \"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\": [\"5712-339372b\"], \"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": "1614162860555070",
"status": "COMPLETED",
"status_changed_ts": "1614162860808244",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18694",
"builder:infra-continuous-win10-64",
"buildset:commit/git/663b7a3715493ef75087e20fe80df1d33373b2c2",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/663b7a3715493ef75087e20fe80df1d33373b2c2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019858079553338032",
"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/8854428017504071248/+/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:51ec0a1d3cfd8510",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1614163420786676",
"url": "https://luci-milo-dev.appspot.com/b/8854428017504071248",
"utcnow_ts": "1614163421368401"
}
}
[10:43:41.375] Invocation finished in 1h56m27.267333364s with status SUCCEEDED