[08:02:04.219] New invocation is queued and will start shortly
[08:02:05.380] Starting the invocation (attempt 1)
[08:02:05.438] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[08:02:05.438] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[08:02:05.438] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9021038681659425856",
"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\":\"7fde662819fe35ffa9c692a4885eb00a1a676751\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@7fde662819fe35ffa9c692a4885eb00a1a676751\",\"title\":\"7fde662819fe35ffa9c692a4885eb00a1a676751\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/7fde662819fe35ffa9c692a4885eb00a1a676751\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"7fde662819fe35ffa9c692a4885eb00a1a676751\"}}",
"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:9021038681659425856",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/7fde662819fe35ffa9c692a4885eb00a1a676751",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/7fde662819fe35ffa9c692a4885eb00a1a676751",
"gitiles_ref:refs/heads/master"
]
}
[08:02:06.089] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1613030525535441",
"id": "8855608619614460816",
"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\": \"7fde662819fe35ffa9c692a4885eb00a1a676751\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@7fde662819fe35ffa9c692a4885eb00a1a676751\", \"title\": \"7fde662819fe35ffa9c692a4885eb00a1a676751\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/7fde662819fe35ffa9c692a4885eb00a1a676751\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"7fde662819fe35ffa9c692a4885eb00a1a676751\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613030525977052",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18583",
"builder:infra-continuous-win10-64",
"buildset:commit/git/7fde662819fe35ffa9c692a4885eb00a1a676751",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/7fde662819fe35ffa9c692a4885eb00a1a676751",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021038681659425856",
"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/8855608619614460816/+/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": "1613030525977161",
"url": "https://luci-milo-dev.appspot.com/b/8855608619614460816",
"utcnow_ts": "1613030526081678"
}
}
[08:02:06.089] Task URL: https://luci-milo-dev.appspot.com/b/8855608619614460816
[08:02:06.089] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:2:0) after 1m0s
[08:03:06.197] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:2:0)
[08:03:06.197] Timer tick, asking Buildbucket for the build status
[08:03:06.270] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:03:06.270] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:3:0) after 1m0s
[08:04:06.295] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:3:0)
[08:04:06.295] Timer tick, asking Buildbucket for the build status
[08:04:06.382] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:04:06.382] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:4:0) after 1m0s
[08:05:06.400] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:4:0)
[08:05:06.400] Timer tick, asking Buildbucket for the build status
[08:05:06.485] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:05:06.485] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:5:0) after 1m0s
[08:06:06.508] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:5:0)
[08:06:06.508] Timer tick, asking Buildbucket for the build status
[08:06:06.598] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:06:06.598] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:6:0) after 1m0s
[08:07:06.645] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:6:0)
[08:07:06.645] Timer tick, asking Buildbucket for the build status
[08:07:06.718] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:07:06.718] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:7:0) after 1m0s
[08:08:06.771] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:7:0)
[08:08:06.771] Timer tick, asking Buildbucket for the build status
[08:08:06.842] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:08:06.842] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:8:0) after 1m0s
[08:09:06.898] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:8:0)
[08:09:06.898] Timer tick, asking Buildbucket for the build status
[08:09:06.973] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:09:06.973] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:9:0) after 1m0s
[08:10:06.998] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:9:0)
[08:10:06.998] Timer tick, asking Buildbucket for the build status
[08:10:07.116] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:10:07.116] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:10:0) after 1m0s
[08:11:07.184] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:10:0)
[08:11:07.184] Timer tick, asking Buildbucket for the build status
[08:11:07.253] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:11:07.253] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:11:0) after 1m0s
[08:12:07.298] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:11:0)
[08:12:07.298] Timer tick, asking Buildbucket for the build status
[08:12:07.367] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:12:07.367] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:12:0) after 1m0s
[08:13:07.388] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:12:0)
[08:13:07.388] Timer tick, asking Buildbucket for the build status
[08:13:07.505] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:13:07.505] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:13:0) after 1m0s
[08:14:07.597] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:13:0)
[08:14:07.597] Timer tick, asking Buildbucket for the build status
[08:14:07.710] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:14:07.710] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:14:0) after 1m0s
[08:15:07.803] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:14:0)
[08:15:07.803] Timer tick, asking Buildbucket for the build status
[08:15:07.867] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:15:07.867] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:15:0) after 1m0s
[08:16:07.889] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:15:0)
[08:16:07.889] Timer tick, asking Buildbucket for the build status
[08:16:07.996] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:16:07.996] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:16:0) after 1m0s
[08:17:08.099] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:16:0)
[08:17:08.099] Timer tick, asking Buildbucket for the build status
[08:17:08.179] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:17:08.179] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:17:0) after 1m0s
[08:18:08.300] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:17:0)
[08:18:08.300] Timer tick, asking Buildbucket for the build status
[08:18:08.399] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:18:08.399] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:18:0) after 1m0s
[08:19:08.487] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:18:0)
[08:19:08.487] Timer tick, asking Buildbucket for the build status
[08:19:08.565] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:19:08.565] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:19:0) after 1m0s
[08:20:08.589] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:19:0)
[08:20:08.589] Timer tick, asking Buildbucket for the build status
[08:20:08.718] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:20:08.718] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:20:0) after 1m0s
[08:21:08.787] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:20:0)
[08:21:08.787] Timer tick, asking Buildbucket for the build status
[08:21:08.876] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:21:08.876] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:21:0) after 1m0s
[08:22:08.899] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:21:0)
[08:22:08.899] Timer tick, asking Buildbucket for the build status
[08:22:09.015] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:22:09.015] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:22:0) after 1m0s
[08:23:09.095] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:22:0)
[08:23:09.095] Timer tick, asking Buildbucket for the build status
[08:23:09.161] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:23:09.161] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:23:0) after 1m0s
[08:24:09.200] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:23:0)
[08:24:09.200] Timer tick, asking Buildbucket for the build status
[08:24:09.327] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:24:09.327] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:24:0) after 1m0s
[08:25:09.387] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:24:0)
[08:25:09.387] Timer tick, asking Buildbucket for the build status
[08:25:09.463] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:25:09.463] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:25:0) after 1m0s
[08:26:09.500] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:25:0)
[08:26:09.500] Timer tick, asking Buildbucket for the build status
[08:26:09.575] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:26:09.575] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:26:0) after 1m0s
[08:27:09.604] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:26:0)
[08:27:09.604] Timer tick, asking Buildbucket for the build status
[08:27:09.692] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:27:09.692] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:27:0) after 1m0s
[08:28:09.801] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:27:0)
[08:28:09.801] Timer tick, asking Buildbucket for the build status
[08:28:09.970] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:28:09.970] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:28:0) after 1m0s
[08:29:09.996] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:28:0)
[08:29:09.996] Timer tick, asking Buildbucket for the build status
[08:29:10.123] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:29:10.123] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:29:0) after 1m0s
[08:30:10.188] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:29:0)
[08:30:10.188] Timer tick, asking Buildbucket for the build status
[08:30:10.265] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:30:10.265] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:30:0) after 1m0s
[08:31:10.294] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:30:0)
[08:31:10.294] Timer tick, asking Buildbucket for the build status
[08:31:10.380] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:31:10.380] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:31:0) after 1m0s
[08:32:10.503] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:31:0)
[08:32:10.503] Timer tick, asking Buildbucket for the build status
[08:32:10.575] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:32:10.575] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:32:0) after 1m0s
[08:33:10.598] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:32:0)
[08:33:10.598] Timer tick, asking Buildbucket for the build status
[08:33:10.678] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:33:10.678] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:33:0) after 1m0s
[08:34:10.699] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:33:0)
[08:34:10.699] Timer tick, asking Buildbucket for the build status
[08:34:10.793] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:34:10.793] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:34:0) after 1m0s
[08:35:10.899] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:34:0)
[08:35:10.899] Timer tick, asking Buildbucket for the build status
[08:35:10.984] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:35:10.984] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:35:0) after 1m0s
[08:36:11.099] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:35:0)
[08:36:11.099] Timer tick, asking Buildbucket for the build status
[08:36:11.177] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:36:11.177] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:36:0) after 1m0s
[08:37:11.198] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:36:0)
[08:37:11.198] Timer tick, asking Buildbucket for the build status
[08:37:11.279] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:37:11.279] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:37:0) after 1m0s
[08:38:11.301] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:37:0)
[08:38:11.301] Timer tick, asking Buildbucket for the build status
[08:38:11.438] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:38:11.438] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:38:0) after 1m0s
[08:39:11.470] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:38:0)
[08:39:11.470] Timer tick, asking Buildbucket for the build status
[08:39:11.545] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:39:11.545] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:39:0) after 1m0s
[08:40:11.590] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:39:0)
[08:40:11.590] Timer tick, asking Buildbucket for the build status
[08:40:11.703] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:40:11.703] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:40:0) after 1m0s
[08:41:11.789] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:40:0)
[08:41:11.789] Timer tick, asking Buildbucket for the build status
[08:41:11.872] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:41:11.872] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:41:0) after 1m0s
[08:42:11.905] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:41:0)
[08:42:11.905] Timer tick, asking Buildbucket for the build status
[08:42:12.026] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:42:12.026] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:42:0) after 1m0s
[08:43:12.054] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:42:0)
[08:43:12.054] Timer tick, asking Buildbucket for the build status
[08:43:12.150] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:43:12.150] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:43:0) after 1m0s
[08:44:12.203] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:43:0)
[08:44:12.203] Timer tick, asking Buildbucket for the build status
[08:44:12.285] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:44:12.285] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:44:0) after 1m0s
[08:45:12.308] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:44:0)
[08:45:12.309] Timer tick, asking Buildbucket for the build status
[08:45:12.427] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:45:12.427] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:45:0) after 1m0s
[08:46:12.510] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:45:0)
[08:46:12.510] Timer tick, asking Buildbucket for the build status
[08:46:12.579] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:46:12.579] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:46:0) after 1m0s
[08:47:12.710] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:46:0)
[08:47:12.710] Timer tick, asking Buildbucket for the build status
[08:47:12.796] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:47:12.796] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:47:0) after 1m0s
[08:48:12.889] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:47:0)
[08:48:12.889] Timer tick, asking Buildbucket for the build status
[08:48:12.956] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:48:12.956] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:48:0) after 1m0s
[08:49:13.004] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:48:0)
[08:49:13.004] Timer tick, asking Buildbucket for the build status
[08:49:13.082] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:49:13.082] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:49:0) after 1m0s
[08:50:13.197] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:49:0)
[08:50:13.197] Timer tick, asking Buildbucket for the build status
[08:50:13.330] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:50:13.330] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:50:0) after 1m0s
[08:51:13.398] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:50:0)
[08:51:13.398] Timer tick, asking Buildbucket for the build status
[08:51:13.479] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:51:13.479] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:51:0) after 1m0s
[08:52:13.505] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:51:0)
[08:52:13.505] Timer tick, asking Buildbucket for the build status
[08:52:13.606] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:52:13.606] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:52:0) after 1m0s
[08:53:13.688] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:52:0)
[08:53:13.688] Timer tick, asking Buildbucket for the build status
[08:53:13.772] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:53:13.772] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:53:0) after 1m0s
[08:54:13.802] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:53:0)
[08:54:13.802] Timer tick, asking Buildbucket for the build status
[08:54:13.886] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:54:13.886] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:54:0) after 1m0s
[08:55:14.042] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:54:0)
[08:55:14.042] Timer tick, asking Buildbucket for the build status
[08:55:14.110] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:55:14.110] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:55:0) after 1m0s
[08:56:14.204] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:55:0)
[08:56:14.204] Timer tick, asking Buildbucket for the build status
[08:56:14.318] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:56:14.318] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:56:0) after 1m0s
[08:57:14.343] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:56:0)
[08:57:14.343] Timer tick, asking Buildbucket for the build status
[08:57:14.477] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:57:14.477] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:57:0) after 1m0s
[08:58:14.501] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:57:0)
[08:58:14.501] Timer tick, asking Buildbucket for the build status
[08:58:14.606] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:58:14.606] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:58:0) after 1m0s
[08:59:14.700] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:58:0)
[08:59:14.700] Timer tick, asking Buildbucket for the build status
[08:59:14.764] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:59:14.764] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:59:0) after 1m0s
[09:00:14.804] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:59:0)
[09:00:14.804] Timer tick, asking Buildbucket for the build status
[09:00:14.910] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:00:14.910] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:60:0) after 1m0s
[09:01:14.989] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:60:0)
[09:01:14.989] Timer tick, asking Buildbucket for the build status
[09:01:15.096] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:01:15.096] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:61:0) after 1m0s
[09:02:15.206] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:61:0)
[09:02:15.206] Timer tick, asking Buildbucket for the build status
[09:02:15.279] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:02:15.279] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:62:0) after 1m0s
[09:03:15.388] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:62:0)
[09:03:15.388] Timer tick, asking Buildbucket for the build status
[09:03:15.481] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:03:15.481] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:63:0) after 1m0s
[09:04:15.595] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:63:0)
[09:04:15.595] Timer tick, asking Buildbucket for the build status
[09:04:15.656] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:04:15.656] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:64:0) after 1m0s
[09:05:15.689] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:64:0)
[09:05:15.689] Timer tick, asking Buildbucket for the build status
[09:05:15.767] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:05:15.767] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:65:0) after 1m0s
[09:06:15.804] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:65:0)
[09:06:15.804] Timer tick, asking Buildbucket for the build status
[09:06:15.867] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:06:15.867] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:66:0) after 1m0s
[09:07:15.914] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:66:0)
[09:07:15.914] Timer tick, asking Buildbucket for the build status
[09:07:16.005] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:07:16.005] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:67:0) after 1m0s
[09:08:16.092] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:67:0)
[09:08:16.092] Timer tick, asking Buildbucket for the build status
[09:08:16.168] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:08:16.168] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:68:0) after 1m0s
[09:09:16.200] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:68:0)
[09:09:16.200] Timer tick, asking Buildbucket for the build status
[09:09:16.268] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:09:16.268] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:69:0) after 1m0s
[09:10:16.291] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:69:0)
[09:10:16.291] Timer tick, asking Buildbucket for the build status
[09:10:16.429] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:10:16.429] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:70:0) after 1m0s
[09:11:16.451] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:70:0)
[09:11:16.451] Timer tick, asking Buildbucket for the build status
[09:11:16.528] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:11:16.528] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:71:0) after 1m0s
[09:12:16.598] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:71:0)
[09:12:16.598] Timer tick, asking Buildbucket for the build status
[09:12:16.673] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:12:16.673] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:72:0) after 1m0s
[09:13:16.699] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:72:0)
[09:13:16.699] Timer tick, asking Buildbucket for the build status
[09:13:16.772] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:13:16.772] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:73:0) after 1m0s
[09:14:16.798] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:73:0)
[09:14:16.798] Timer tick, asking Buildbucket for the build status
[09:14:16.878] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:14:16.878] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:74:0) after 1m0s
[09:15:16.901] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:74:0)
[09:15:16.901] Timer tick, asking Buildbucket for the build status
[09:15:16.976] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:15:16.976] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:75:0) after 1m0s
[09:16:17.000] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:75:0)
[09:16:17.000] Timer tick, asking Buildbucket for the build status
[09:16:17.066] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:16:17.066] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:76:0) after 1m0s
[09:17:17.092] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:76:0)
[09:17:17.092] Timer tick, asking Buildbucket for the build status
[09:17:17.159] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:17:17.159] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:77:0) after 1m0s
[09:18:17.202] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:77:0)
[09:18:17.202] Timer tick, asking Buildbucket for the build status
[09:18:17.310] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:18:17.310] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:78:0) after 1m0s
[09:19:17.390] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:78:0)
[09:19:17.390] Timer tick, asking Buildbucket for the build status
[09:19:17.504] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:19:17.504] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:79:0) after 1m0s
[09:20:17.589] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:79:0)
[09:20:17.589] Timer tick, asking Buildbucket for the build status
[09:20:17.681] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:20:17.681] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:80:0) after 1m0s
[09:21:17.708] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:80:0)
[09:21:17.708] Timer tick, asking Buildbucket for the build status
[09:21:17.777] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:21:17.777] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:81:0) after 1m0s
[09:22:17.804] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:81:0)
[09:22:17.804] Timer tick, asking Buildbucket for the build status
[09:22:17.882] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:22:17.882] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:82:0) after 1m0s
[09:23:17.989] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:82:0)
[09:23:17.989] Timer tick, asking Buildbucket for the build status
[09:23:18.074] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:23:18.074] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:83:0) after 1m0s
[09:24:18.101] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:83:0)
[09:24:18.101] Timer tick, asking Buildbucket for the build status
[09:24:18.181] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:24:18.182] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:84:0) after 1m0s
[09:25:18.228] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:84:0)
[09:25:18.228] Timer tick, asking Buildbucket for the build status
[09:25:18.299] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:25:18.299] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:85:0) after 1m0s
[09:26:18.403] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:85:0)
[09:26:18.403] Timer tick, asking Buildbucket for the build status
[09:26:18.469] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:26:18.469] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:86:0) after 1m0s
[09:27:18.501] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:86:0)
[09:27:18.501] Timer tick, asking Buildbucket for the build status
[09:27:18.572] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:27:18.572] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:87:0) after 1m0s
[09:28:18.600] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:87:0)
[09:28:18.600] Timer tick, asking Buildbucket for the build status
[09:28:18.684] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:28:18.684] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:88:0) after 1m0s
[09:29:18.712] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:88:0)
[09:29:18.712] Timer tick, asking Buildbucket for the build status
[09:29:18.797] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:29:18.797] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:89:0) after 1m0s
[09:30:18.889] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:89:0)
[09:30:18.889] Timer tick, asking Buildbucket for the build status
[09:30:18.969] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:30:18.969] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:90:0) after 1m0s
[09:31:18.995] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:90:0)
[09:31:18.995] Timer tick, asking Buildbucket for the build status
[09:31:19.067] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:31:19.067] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:91:0) after 1m0s
[09:32:19.100] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:91:0)
[09:32:19.100] Timer tick, asking Buildbucket for the build status
[09:32:19.203] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:32:19.203] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:92:0) after 1m0s
[09:33:19.300] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:92:0)
[09:33:19.300] Timer tick, asking Buildbucket for the build status
[09:33:19.380] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:33:19.381] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:93:0) after 1m0s
[09:34:19.493] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:93:0)
[09:34:19.493] Timer tick, asking Buildbucket for the build status
[09:34:19.597] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:34:19.597] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:94:0) after 1m0s
[09:35:19.701] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:94:0)
[09:35:19.701] Timer tick, asking Buildbucket for the build status
[09:35:19.813] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:35:19.813] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:95:0) after 1m0s
[09:36:19.905] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:95:0)
[09:36:19.905] Timer tick, asking Buildbucket for the build status
[09:36:19.991] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:36:19.991] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:96:0) after 1m0s
[09:37:20.088] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:96:0)
[09:37:20.088] Timer tick, asking Buildbucket for the build status
[09:37:20.180] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:37:20.180] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:97:0) after 1m0s
[09:38:20.203] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:97:0)
[09:38:20.203] Timer tick, asking Buildbucket for the build status
[09:38:20.264] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:38:20.264] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:98:0) after 1m0s
[09:39:20.299] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:98:0)
[09:39:20.299] Timer tick, asking Buildbucket for the build status
[09:39:20.377] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:39:20.377] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:99:0) after 1m0s
[09:40:20.405] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:99:0)
[09:40:20.405] Timer tick, asking Buildbucket for the build status
[09:40:20.497] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:40:20.497] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:100:0) after 1m0s
[09:41:20.589] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:100:0)
[09:41:20.589] Timer tick, asking Buildbucket for the build status
[09:41:20.720] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:41:20.720] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:101:0) after 1m0s
[09:42:20.748] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:101:0)
[09:42:20.748] Timer tick, asking Buildbucket for the build status
[09:42:20.830] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:42:20.830] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:102:0) after 1m0s
[09:43:20.889] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:102:0)
[09:43:20.889] Timer tick, asking Buildbucket for the build status
[09:43:20.977] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:43:20.977] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:103:0) after 1m0s
[09:44:21.001] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:103:0)
[09:44:21.001] Timer tick, asking Buildbucket for the build status
[09:44:21.065] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:44:21.065] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:104:0) after 1m0s
[09:45:21.089] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:104:0)
[09:45:21.089] Timer tick, asking Buildbucket for the build status
[09:45:21.174] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:45:21.174] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:105:0) after 1m0s
[09:46:21.201] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:105:0)
[09:46:21.201] Timer tick, asking Buildbucket for the build status
[09:46:21.267] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:46:21.267] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:106:0) after 1m0s
[09:47:21.300] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:106:0)
[09:47:21.300] Timer tick, asking Buildbucket for the build status
[09:47:21.370] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:47:21.370] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:107:0) after 1m0s
[09:48:21.394] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:107:0)
[09:48:21.394] Timer tick, asking Buildbucket for the build status
[09:48:21.463] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:48:21.463] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:108:0) after 1m0s
[09:49:21.499] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:108:0)
[09:49:21.500] Timer tick, asking Buildbucket for the build status
[09:49:21.586] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:49:21.586] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:109:0) after 1m0s
[09:50:21.606] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:109:0)
[09:50:21.606] Timer tick, asking Buildbucket for the build status
[09:50:21.678] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:50:21.678] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:110:0) after 1m0s
[09:51:21.709] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:110:0)
[09:51:21.709] Timer tick, asking Buildbucket for the build status
[09:51:21.793] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:51:21.793] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:111:0) after 1m0s
[09:52:21.909] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:111:0)
[09:52:21.909] Timer tick, asking Buildbucket for the build status
[09:52:21.995] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:52:21.995] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:112:0) after 1m0s
[09:53:22.100] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:112:0)
[09:53:22.100] Timer tick, asking Buildbucket for the build status
[09:53:22.171] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:53:22.171] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:113:0) after 1m0s
[09:54:22.193] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:113:0)
[09:54:22.193] Timer tick, asking Buildbucket for the build status
[09:54:22.270] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:54:22.270] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:114:0) after 1m0s
[09:55:22.296] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:114:0)
[09:55:22.296] Timer tick, asking Buildbucket for the build status
[09:55:22.404] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:55:22.404] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:115:0) after 1m0s
[09:56:22.511] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:115:0)
[09:56:22.511] Timer tick, asking Buildbucket for the build status
[09:56:22.597] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:56:22.597] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:116:0) after 1m0s
[09:57:22.690] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:116:0)
[09:57:22.690] Timer tick, asking Buildbucket for the build status
[09:57:22.755] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:57:22.755] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:117:0) after 1m0s
[09:58:22.808] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:117:0)
[09:58:22.808] Timer tick, asking Buildbucket for the build status
[09:58:22.891] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:58:22.891] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:118:0) after 1m0s
[09:59:22.989] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:118:0)
[09:59:22.989] Timer tick, asking Buildbucket for the build status
[09:59:23.061] Build 8855608619614460816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:59:23.061] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:119:0) after 1m0s
[09:59:24.704] Received PubSub notification, asking Buildbucket for the build status
[09:59:24.771] Build 8855608619614460816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:23.089] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:119:0)
[10:00:23.089] Timer tick, asking Buildbucket for the build status
[10:00:23.164] Build 8855608619614460816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:23.164] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:121:0) after 1m0s
[10:01:23.250] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:121:0)
[10:01:23.250] Timer tick, asking Buildbucket for the build status
[10:01:23.354] Build 8855608619614460816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:23.354] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:122:0) after 1m0s
[10:02:23.403] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:122:0)
[10:02:23.403] Timer tick, asking Buildbucket for the build status
[10:02:23.526] Build 8855608619614460816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:23.526] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:123:0) after 1m0s
[10:03:23.556] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:123:0)
[10:03:23.556] Timer tick, asking Buildbucket for the build status
[10:03:23.702] Build 8855608619614460816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:23.702] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021038681659425856:124:0) after 1m0s
[10:03:28.764] Received PubSub notification, asking Buildbucket for the build status
[10:03:28.842] Build 8855608619614460816: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:03:28.842] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1613037808449187",
"created_by": "project:infra",
"created_ts": "1613030525535441",
"id": "8855608619614460816",
"lease_expiration_ts": "-62135596800000000",
"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\": \"7fde662819fe35ffa9c692a4885eb00a1a676751\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@7fde662819fe35ffa9c692a4885eb00a1a676751\", \"title\": \"7fde662819fe35ffa9c692a4885eb00a1a676751\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/7fde662819fe35ffa9c692a4885eb00a1a676751\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"7fde662819fe35ffa9c692a4885eb00a1a676751\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"7fde662819fe35ffa9c692a4885eb00a1a676751\", \"got_revision_cp\": \"refs/heads/master@{#38451}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_044445a3bc7a4bfe795493d7956aa02cd526172de1fa7300f69fe152e82ea631_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-fp5j\"], \"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\": [\"swarming-dev-default-v022\"], \"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": "1613037442108192",
"status": "COMPLETED",
"status_changed_ts": "1613037563441272",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18583",
"builder:infra-continuous-win10-64",
"buildset:commit/git/7fde662819fe35ffa9c692a4885eb00a1a676751",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/7fde662819fe35ffa9c692a4885eb00a1a676751",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021038681659425856",
"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/8855608619614460816/+/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:51a8ee150eeef410",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1613037563450745",
"url": "https://luci-milo-dev.appspot.com/b/8855608619614460816",
"utcnow_ts": "1613037808835354"
}
}
[10:03:28.842] Invocation finished in 2h1m24.639834459s with status SUCCEEDED