[09:19:52.097] New invocation is queued and will start shortly
[09:19:53.203] Starting the invocation (attempt 1)
[09:19:53.267] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[09:19:53.267] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[09:19:53.267] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9022211547599511024",
"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\":\"619ad99e5ac9eec2b24772423016108cdd74a48f\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@619ad99e5ac9eec2b24772423016108cdd74a48f\",\"title\":\"619ad99e5ac9eec2b24772423016108cdd74a48f\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/619ad99e5ac9eec2b24772423016108cdd74a48f\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"619ad99e5ac9eec2b24772423016108cdd74a48f\"}}",
"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:9022211547599511024",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/619ad99e5ac9eec2b24772423016108cdd74a48f",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/619ad99e5ac9eec2b24772423016108cdd74a48f",
"gitiles_ref:refs/heads/master"
]
}
[09:19:53.998] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:infra",
"created_ts": "1611911993361724",
"id": "8856781485608016704",
"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\": \"619ad99e5ac9eec2b24772423016108cdd74a48f\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@619ad99e5ac9eec2b24772423016108cdd74a48f\", \"title\": \"619ad99e5ac9eec2b24772423016108cdd74a48f\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/619ad99e5ac9eec2b24772423016108cdd74a48f\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"619ad99e5ac9eec2b24772423016108cdd74a48f\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611911993822134",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18463",
"builder:infra-continuous-win10-64",
"buildset:commit/git/619ad99e5ac9eec2b24772423016108cdd74a48f",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/619ad99e5ac9eec2b24772423016108cdd74a48f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022211547599511024",
"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/8856781485608016704/+/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": "1611911993822256",
"url": "https://luci-milo-dev.appspot.com/b/8856781485608016704",
"utcnow_ts": "1611911993989515"
}
}
[09:19:53.998] Task URL: https://luci-milo-dev.appspot.com/b/8856781485608016704
[09:19:53.998] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:2:0) after 1m0s
[09:20:54.108] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:2:0)
[09:20:54.108] Timer tick, asking Buildbucket for the build status
[09:20:54.250] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:20:54.250] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:3:0) after 1m0s
[09:21:54.372] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:3:0)
[09:21:54.372] Timer tick, asking Buildbucket for the build status
[09:21:54.463] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:21:54.463] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:4:0) after 1m0s
[09:22:54.556] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:4:0)
[09:22:54.556] Timer tick, asking Buildbucket for the build status
[09:22:54.636] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:22:54.636] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:5:0) after 1m0s
[09:23:54.662] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:5:0)
[09:23:54.662] Timer tick, asking Buildbucket for the build status
[09:23:54.793] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:23:54.793] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:6:0) after 1m0s
[09:24:54.854] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:6:0)
[09:24:54.854] Timer tick, asking Buildbucket for the build status
[09:24:54.938] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:24:54.938] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:7:0) after 1m0s
[09:25:54.961] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:7:0)
[09:25:54.961] Timer tick, asking Buildbucket for the build status
[09:25:55.047] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:25:55.047] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:8:0) after 1m0s
[09:26:55.071] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:8:0)
[09:26:55.071] Timer tick, asking Buildbucket for the build status
[09:26:55.169] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:26:55.169] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:9:0) after 1m0s
[09:27:55.267] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:9:0)
[09:27:55.267] Timer tick, asking Buildbucket for the build status
[09:27:55.341] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:27:55.341] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:10:0) after 1m0s
[09:28:55.388] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:10:0)
[09:28:55.388] Timer tick, asking Buildbucket for the build status
[09:28:55.497] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:28:55.497] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:11:0) after 1m0s
[09:29:55.609] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:11:0)
[09:29:55.609] Timer tick, asking Buildbucket for the build status
[09:29:55.710] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:29:55.710] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:12:0) after 1m0s
[09:30:55.805] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:12:0)
[09:30:55.805] Timer tick, asking Buildbucket for the build status
[09:30:55.900] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:30:55.900] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:13:0) after 1m0s
[09:31:55.925] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:13:0)
[09:31:55.925] Timer tick, asking Buildbucket for the build status
[09:31:56.017] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:31:56.017] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:14:0) after 1m0s
[09:32:56.105] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:14:0)
[09:32:56.105] Timer tick, asking Buildbucket for the build status
[09:32:56.198] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:32:56.198] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:15:0) after 1m0s
[09:33:56.294] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:15:0)
[09:33:56.294] Timer tick, asking Buildbucket for the build status
[09:33:56.375] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:33:56.375] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:16:0) after 1m0s
[09:34:56.401] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:16:0)
[09:34:56.401] Timer tick, asking Buildbucket for the build status
[09:34:56.548] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:34:56.548] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:17:0) after 1m0s
[09:35:56.668] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:17:0)
[09:35:56.668] Timer tick, asking Buildbucket for the build status
[09:35:56.785] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:35:56.785] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:18:0) after 1m0s
[09:36:56.867] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:18:0)
[09:36:56.867] Timer tick, asking Buildbucket for the build status
[09:36:56.963] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:36:56.963] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:19:0) after 1m0s
[09:37:56.993] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:19:0)
[09:37:56.993] Timer tick, asking Buildbucket for the build status
[09:37:57.101] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:37:57.101] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:20:0) after 1m0s
[09:38:57.156] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:20:0)
[09:38:57.156] Timer tick, asking Buildbucket for the build status
[09:38:57.354] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:38:57.354] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:21:0) after 1m0s
[09:39:57.475] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:21:0)
[09:39:57.475] Timer tick, asking Buildbucket for the build status
[09:39:57.612] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:39:57.612] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:22:0) after 1m0s
[09:40:57.715] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:22:0)
[09:40:57.715] Timer tick, asking Buildbucket for the build status
[09:40:57.806] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:40:57.806] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:23:0) after 1m0s
[09:41:57.917] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:23:0)
[09:41:57.917] Timer tick, asking Buildbucket for the build status
[09:41:58.051] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:41:58.051] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:24:0) after 1m0s
[09:42:58.118] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:24:0)
[09:42:58.118] Timer tick, asking Buildbucket for the build status
[09:42:58.234] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:42:58.234] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:25:0) after 1m0s
[09:43:58.305] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:25:0)
[09:43:58.305] Timer tick, asking Buildbucket for the build status
[09:43:58.406] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:43:58.406] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:26:0) after 1m0s
[09:44:58.428] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:26:0)
[09:44:58.428] Timer tick, asking Buildbucket for the build status
[09:44:58.509] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:44:58.510] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:27:0) after 1m0s
[09:45:58.572] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:27:0)
[09:45:58.572] Timer tick, asking Buildbucket for the build status
[09:45:58.692] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:45:58.692] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:28:0) after 1m0s
[09:46:58.793] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:28:0)
[09:46:58.793] Timer tick, asking Buildbucket for the build status
[09:46:58.873] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:46:58.873] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:29:0) after 1m0s
[09:47:58.895] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:29:0)
[09:47:58.895] Timer tick, asking Buildbucket for the build status
[09:47:58.990] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:47:58.990] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:30:0) after 1m0s
[09:48:59.069] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:30:0)
[09:48:59.069] Timer tick, asking Buildbucket for the build status
[09:48:59.162] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:48:59.162] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:31:0) after 1m0s
[09:49:59.272] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:31:0)
[09:49:59.272] Timer tick, asking Buildbucket for the build status
[09:49:59.353] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:49:59.353] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:32:0) after 1m0s
[09:50:59.376] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:32:0)
[09:50:59.376] Timer tick, asking Buildbucket for the build status
[09:50:59.449] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:50:59.449] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:33:0) after 1m0s
[09:51:59.569] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:33:0)
[09:51:59.569] Timer tick, asking Buildbucket for the build status
[09:51:59.653] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:51:59.653] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:34:0) after 1m0s
[09:52:59.779] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:34:0)
[09:52:59.779] Timer tick, asking Buildbucket for the build status
[09:52:59.868] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:52:59.868] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:35:0) after 1m0s
[09:53:59.958] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:35:0)
[09:53:59.958] Timer tick, asking Buildbucket for the build status
[09:54:00.085] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:54:00.085] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:36:0) after 1m0s
[09:55:00.109] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:36:0)
[09:55:00.109] Timer tick, asking Buildbucket for the build status
[09:55:00.333] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:55:00.333] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:37:0) after 1m0s
[09:56:00.361] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:37:0)
[09:56:00.361] Timer tick, asking Buildbucket for the build status
[09:56:00.474] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:56:00.474] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:38:0) after 1m0s
[09:57:00.499] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:38:0)
[09:57:00.499] Timer tick, asking Buildbucket for the build status
[09:57:00.572] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:57:00.572] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:39:0) after 1m0s
[09:58:00.599] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:39:0)
[09:58:00.599] Timer tick, asking Buildbucket for the build status
[09:58:00.698] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:58:00.698] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:40:0) after 1m0s
[09:59:00.734] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:40:0)
[09:59:00.734] Timer tick, asking Buildbucket for the build status
[09:59:00.897] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:59:00.897] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:41:0) after 1m0s
[10:00:00.917] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:41:0)
[10:00:00.917] Timer tick, asking Buildbucket for the build status
[10:00:01.175] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:00:01.175] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:42:0) after 1m0s
[10:01:01.232] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:42:0)
[10:01:01.233] Timer tick, asking Buildbucket for the build status
[10:01:01.345] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:01:01.345] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:43:0) after 1m0s
[10:02:01.382] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:43:0)
[10:02:01.382] Timer tick, asking Buildbucket for the build status
[10:02:01.492] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:02:01.492] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:44:0) after 1m0s
[10:03:01.557] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:44:0)
[10:03:01.557] Timer tick, asking Buildbucket for the build status
[10:03:01.639] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:03:01.639] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:45:0) after 1m0s
[10:04:01.664] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:45:0)
[10:04:01.664] Timer tick, asking Buildbucket for the build status
[10:04:01.742] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:04:01.742] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:46:0) after 1m0s
[10:05:01.806] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:46:0)
[10:05:01.806] Timer tick, asking Buildbucket for the build status
[10:05:01.896] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:05:01.896] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:47:0) after 1m0s
[10:06:01.922] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:47:0)
[10:06:01.922] Timer tick, asking Buildbucket for the build status
[10:06:02.053] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:06:02.053] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:48:0) after 1m0s
[10:07:02.164] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:48:0)
[10:07:02.164] Timer tick, asking Buildbucket for the build status
[10:07:02.252] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:07:02.252] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:49:0) after 1m0s
[10:08:02.271] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:49:0)
[10:08:02.271] Timer tick, asking Buildbucket for the build status
[10:08:02.382] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:08:02.382] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:50:0) after 1m0s
[10:09:02.408] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:50:0)
[10:09:02.408] Timer tick, asking Buildbucket for the build status
[10:09:02.495] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:09:02.495] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:51:0) after 1m0s
[10:10:02.520] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:51:0)
[10:10:02.520] Timer tick, asking Buildbucket for the build status
[10:10:02.608] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:10:02.608] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:52:0) after 1m0s
[10:11:02.632] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:52:0)
[10:11:02.632] Timer tick, asking Buildbucket for the build status
[10:11:02.729] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:11:02.729] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:53:0) after 1m0s
[10:12:02.825] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:53:0)
[10:12:02.825] Timer tick, asking Buildbucket for the build status
[10:12:02.916] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:12:02.916] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:54:0) after 1m0s
[10:13:02.973] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:54:0)
[10:13:02.973] Timer tick, asking Buildbucket for the build status
[10:13:03.069] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:13:03.069] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:55:0) after 1m0s
[10:14:03.156] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:55:0)
[10:14:03.156] Timer tick, asking Buildbucket for the build status
[10:14:03.259] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:14:03.259] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:56:0) after 1m0s
[10:15:03.360] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:56:0)
[10:15:03.360] Timer tick, asking Buildbucket for the build status
[10:15:03.476] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:15:03.476] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:57:0) after 1m0s
[10:16:03.508] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:57:0)
[10:16:03.508] Timer tick, asking Buildbucket for the build status
[10:16:03.605] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:16:03.605] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:58:0) after 1m0s
[10:17:03.692] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:58:0)
[10:17:03.692] Timer tick, asking Buildbucket for the build status
[10:17:03.776] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:17:03.776] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:59:0) after 1m0s
[10:18:03.818] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:59:0)
[10:18:03.818] Timer tick, asking Buildbucket for the build status
[10:18:03.908] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:18:03.908] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:60:0) after 1m0s
[10:19:03.929] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:60:0)
[10:19:03.929] Timer tick, asking Buildbucket for the build status
[10:19:04.016] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:19:04.016] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:61:0) after 1m0s
[10:20:04.049] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:61:0)
[10:20:04.049] Timer tick, asking Buildbucket for the build status
[10:20:04.126] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:20:04.126] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:62:0) after 1m0s
[10:21:04.171] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:62:0)
[10:21:04.171] Timer tick, asking Buildbucket for the build status
[10:21:04.267] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:21:04.267] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:63:0) after 1m0s
[10:22:04.296] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:63:0)
[10:22:04.296] Timer tick, asking Buildbucket for the build status
[10:22:04.382] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:22:04.382] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:64:0) after 1m0s
[10:23:04.506] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:64:0)
[10:23:04.506] Timer tick, asking Buildbucket for the build status
[10:23:04.607] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:23:04.607] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:65:0) after 1m0s
[10:24:04.647] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:65:0)
[10:24:04.647] Timer tick, asking Buildbucket for the build status
[10:24:04.737] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:24:04.737] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:66:0) after 1m0s
[10:25:04.765] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:66:0)
[10:25:04.765] Timer tick, asking Buildbucket for the build status
[10:25:04.836] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:25:04.836] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:67:0) after 1m0s
[10:26:04.861] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:67:0)
[10:26:04.861] Timer tick, asking Buildbucket for the build status
[10:26:04.937] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:26:04.937] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:68:0) after 1m0s
[10:27:04.966] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:68:0)
[10:27:04.966] Timer tick, asking Buildbucket for the build status
[10:27:05.053] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:27:05.054] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:69:0) after 1m0s
[10:28:05.080] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:69:0)
[10:28:05.080] Timer tick, asking Buildbucket for the build status
[10:28:05.185] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:28:05.185] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:70:0) after 1m0s
[10:29:05.276] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:70:0)
[10:29:05.276] Timer tick, asking Buildbucket for the build status
[10:29:05.371] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:29:05.372] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:71:0) after 1m0s
[10:30:05.405] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:71:0)
[10:30:05.405] Timer tick, asking Buildbucket for the build status
[10:30:05.493] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:30:05.493] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:72:0) after 1m0s
[10:31:05.558] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:72:0)
[10:31:05.558] Timer tick, asking Buildbucket for the build status
[10:31:05.634] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:31:05.634] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:73:0) after 1m0s
[10:32:05.661] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:73:0)
[10:32:05.661] Timer tick, asking Buildbucket for the build status
[10:32:05.743] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:32:05.743] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:74:0) after 1m0s
[10:33:05.768] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:74:0)
[10:33:05.768] Timer tick, asking Buildbucket for the build status
[10:33:05.857] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:33:05.857] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:75:0) after 1m0s
[10:34:05.895] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:75:0)
[10:34:05.895] Timer tick, asking Buildbucket for the build status
[10:34:05.997] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:34:05.997] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:76:0) after 1m0s
[10:35:06.057] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:76:0)
[10:35:06.057] Timer tick, asking Buildbucket for the build status
[10:35:06.152] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:35:06.152] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:77:0) after 1m0s
[10:36:06.257] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:77:0)
[10:36:06.258] Timer tick, asking Buildbucket for the build status
[10:36:06.337] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:36:06.337] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:78:0) after 1m0s
[10:37:06.361] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:78:0)
[10:37:06.361] Timer tick, asking Buildbucket for the build status
[10:37:06.503] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:37:06.503] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:79:0) after 1m0s
[10:38:06.600] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:79:0)
[10:38:06.600] Timer tick, asking Buildbucket for the build status
[10:38:06.699] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:38:06.699] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:80:0) after 1m0s
[10:39:06.771] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:80:0)
[10:39:06.771] Timer tick, asking Buildbucket for the build status
[10:39:06.860] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:39:06.860] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:81:0) after 1m0s
[10:40:06.883] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:81:0)
[10:40:06.883] Timer tick, asking Buildbucket for the build status
[10:40:06.963] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:40:06.963] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:82:0) after 1m0s
[10:41:06.985] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:82:0)
[10:41:06.985] Timer tick, asking Buildbucket for the build status
[10:41:07.069] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:41:07.069] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:83:0) after 1m0s
[10:42:07.104] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:83:0)
[10:42:07.104] Timer tick, asking Buildbucket for the build status
[10:42:07.193] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:42:07.193] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:84:0) after 1m0s
[10:43:07.309] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:84:0)
[10:43:07.309] Timer tick, asking Buildbucket for the build status
[10:43:07.393] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:43:07.393] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:85:0) after 1m0s
[10:44:07.512] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:85:0)
[10:44:07.513] Timer tick, asking Buildbucket for the build status
[10:44:07.605] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:44:07.605] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:86:0) after 1m0s
[10:45:07.706] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:86:0)
[10:45:07.706] Timer tick, asking Buildbucket for the build status
[10:45:07.790] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:45:07.790] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:87:0) after 1m0s
[10:46:07.873] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:87:0)
[10:46:07.873] Timer tick, asking Buildbucket for the build status
[10:46:07.958] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:46:07.958] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:88:0) after 1m0s
[10:47:07.990] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:88:0)
[10:47:07.990] Timer tick, asking Buildbucket for the build status
[10:47:08.091] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:47:08.091] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:89:0) after 1m0s
[10:48:08.131] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:89:0)
[10:48:08.131] Timer tick, asking Buildbucket for the build status
[10:48:08.226] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:48:08.226] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:90:0) after 1m0s
[10:49:08.263] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:90:0)
[10:49:08.263] Timer tick, asking Buildbucket for the build status
[10:49:08.337] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:49:08.337] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:91:0) after 1m0s
[10:50:08.386] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:91:0)
[10:50:08.386] Timer tick, asking Buildbucket for the build status
[10:50:08.464] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:50:08.464] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:92:0) after 1m0s
[10:51:08.489] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:92:0)
[10:51:08.489] Timer tick, asking Buildbucket for the build status
[10:51:08.552] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:51:08.552] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:93:0) after 1m0s
[10:52:08.586] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:93:0)
[10:52:08.586] Timer tick, asking Buildbucket for the build status
[10:52:08.659] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:52:08.659] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:94:0) after 1m0s
[10:53:08.770] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:94:0)
[10:53:08.770] Timer tick, asking Buildbucket for the build status
[10:53:08.896] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:53:08.896] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:95:0) after 1m0s
[10:54:08.995] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:95:0)
[10:54:08.995] Timer tick, asking Buildbucket for the build status
[10:54:09.084] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:54:09.084] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:96:0) after 1m0s
[10:55:09.129] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:96:0)
[10:55:09.129] Timer tick, asking Buildbucket for the build status
[10:55:09.208] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:55:09.208] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:97:0) after 1m0s
[10:56:09.259] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:97:0)
[10:56:09.259] Timer tick, asking Buildbucket for the build status
[10:56:09.339] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:56:09.339] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:98:0) after 1m0s
[10:57:09.366] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:98:0)
[10:57:09.366] Timer tick, asking Buildbucket for the build status
[10:57:09.435] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:57:09.435] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:99:0) after 1m0s
[10:58:09.460] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:99:0)
[10:58:09.460] Timer tick, asking Buildbucket for the build status
[10:58:09.560] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:58:09.560] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:100:0) after 1m0s
[10:59:09.659] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:100:0)
[10:59:09.659] Timer tick, asking Buildbucket for the build status
[10:59:09.809] Build 8856781485608016704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:59:09.809] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:101:0) after 1m0s
[10:59:49.592] Received PubSub notification, asking Buildbucket for the build status
[10:59:49.672] Build 8856781485608016704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:09.873] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:101:0)
[11:00:09.873] Timer tick, asking Buildbucket for the build status
[11:00:09.980] Build 8856781485608016704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:09.980] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:103:0) after 1m0s
[11:01:10.036] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:103:0)
[11:01:10.036] Timer tick, asking Buildbucket for the build status
[11:01:10.138] Build 8856781485608016704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:10.138] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:104:0) after 1m0s
[11:02:10.165] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:104:0)
[11:02:10.165] Timer tick, asking Buildbucket for the build status
[11:02:10.247] Build 8856781485608016704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:10.247] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:105:0) after 1m0s
[11:03:10.282] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:105:0)
[11:03:10.282] Timer tick, asking Buildbucket for the build status
[11:03:10.366] Build 8856781485608016704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:10.366] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:106:0) after 1m0s
[11:04:10.391] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:106:0)
[11:04:10.391] Timer tick, asking Buildbucket for the build status
[11:04:10.485] Build 8856781485608016704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:10.485] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:107:0) after 1m0s
[11:05:10.596] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:107:0)
[11:05:10.596] Timer tick, asking Buildbucket for the build status
[11:05:10.668] Build 8856781485608016704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:10.668] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:108:0) after 1m0s
[11:06:10.691] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:108:0)
[11:06:10.691] Timer tick, asking Buildbucket for the build status
[11:06:10.819] Build 8856781485608016704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:10.819] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:109:0) after 1m0s
[11:07:10.878] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:109:0)
[11:07:10.878] Timer tick, asking Buildbucket for the build status
[11:07:11.006] Build 8856781485608016704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:11.006] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:110:0) after 1m0s
[11:08:11.096] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:110:0)
[11:08:11.096] Timer tick, asking Buildbucket for the build status
[11:08:11.185] Build 8856781485608016704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:11.185] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:111:0) after 1m0s
[11:09:11.272] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:111:0)
[11:09:11.272] Timer tick, asking Buildbucket for the build status
[11:09:11.360] Build 8856781485608016704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:11.360] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:112:0) after 1m0s
[11:10:11.414] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:112:0)
[11:10:11.414] Timer tick, asking Buildbucket for the build status
[11:10:11.548] Build 8856781485608016704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:11.548] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022211547599511024:113:0) after 1m0s
[11:11:05.895] Received PubSub notification, asking Buildbucket for the build status
[11:11:05.979] Build 8856781485608016704: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:11:05.979] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1611918663199223",
"created_by": "project:infra",
"created_ts": "1611911993361724",
"id": "8856781485608016704",
"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\": \"619ad99e5ac9eec2b24772423016108cdd74a48f\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@619ad99e5ac9eec2b24772423016108cdd74a48f\", \"title\": \"619ad99e5ac9eec2b24772423016108cdd74a48f\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/619ad99e5ac9eec2b24772423016108cdd74a48f\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"619ad99e5ac9eec2b24772423016108cdd74a48f\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"619ad99e5ac9eec2b24772423016108cdd74a48f\", \"got_revision_cp\": \"refs/heads/master@{#38048}\"}, \"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-zfw5\"], \"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\": [\"5673-2413400\"], \"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": "1611917983726516",
"status": "COMPLETED",
"status_changed_ts": "1611918663560760",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18463",
"builder:infra-continuous-win10-64",
"buildset:commit/git/619ad99e5ac9eec2b24772423016108cdd74a48f",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/619ad99e5ac9eec2b24772423016108cdd74a48f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022211547599511024",
"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/8856781485608016704/+/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:516642a2e10de910",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1611918663572972",
"url": "https://luci-milo-dev.appspot.com/b/8856781485608016704",
"utcnow_ts": "1611918665968373"
}
}
[11:11:05.979] Invocation finished in 1h51m13.900260236s with status SUCCEEDED