[00:20:54.135] New invocation is queued and will start shortly
[00:20:55.227] Starting the invocation (attempt 1)
[00:20:55.335] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[00:20:55.339] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[00:20:55.340] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9026050528998648448",
"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\":\"10fd6790788326cb38759544d77a57a3904581d1\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@10fd6790788326cb38759544d77a57a3904581d1\",\"title\":\"10fd6790788326cb38759544d77a57a3904581d1\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/10fd6790788326cb38759544d77a57a3904581d1\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"fea24d0599799dfd3959dbf899e019c7d6b55f4d\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@fea24d0599799dfd3959dbf899e019c7d6b55f4d\",\"title\":\"fea24d0599799dfd3959dbf899e019c7d6b55f4d\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/fea24d0599799dfd3959dbf899e019c7d6b55f4d\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"65c7d780b0c85f3d066e9eb67d331ec9cabd583e\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@65c7d780b0c85f3d066e9eb67d331ec9cabd583e\",\"title\":\"65c7d780b0c85f3d066e9eb67d331ec9cabd583e\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/65c7d780b0c85f3d066e9eb67d331ec9cabd583e\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"65c7d780b0c85f3d066e9eb67d331ec9cabd583e\"}}",
"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:9026050528998648448",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/65c7d780b0c85f3d066e9eb67d331ec9cabd583e",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/65c7d780b0c85f3d066e9eb67d331ec9cabd583e",
"gitiles_ref:refs/heads/master"
]
}
[00:20:56.207] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1608250855503757",
"id": "8860620466898300528",
"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\": \"10fd6790788326cb38759544d77a57a3904581d1\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@10fd6790788326cb38759544d77a57a3904581d1\", \"title\": \"10fd6790788326cb38759544d77a57a3904581d1\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/10fd6790788326cb38759544d77a57a3904581d1\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"fea24d0599799dfd3959dbf899e019c7d6b55f4d\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@fea24d0599799dfd3959dbf899e019c7d6b55f4d\", \"title\": \"fea24d0599799dfd3959dbf899e019c7d6b55f4d\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/fea24d0599799dfd3959dbf899e019c7d6b55f4d\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"65c7d780b0c85f3d066e9eb67d331ec9cabd583e\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@65c7d780b0c85f3d066e9eb67d331ec9cabd583e\", \"title\": \"65c7d780b0c85f3d066e9eb67d331ec9cabd583e\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/65c7d780b0c85f3d066e9eb67d331ec9cabd583e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"65c7d780b0c85f3d066e9eb67d331ec9cabd583e\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608250856085992",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18187",
"builder:infra-continuous-win10-64",
"buildset:commit/git/65c7d780b0c85f3d066e9eb67d331ec9cabd583e",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/65c7d780b0c85f3d066e9eb67d331ec9cabd583e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026050528998648448",
"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/8860620466898300528/+/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": "1608250856086107",
"url": "https://luci-milo-dev.appspot.com/b/8860620466898300528",
"utcnow_ts": "1608250856198559"
}
}
[00:20:56.207] Task URL: https://luci-milo-dev.appspot.com/b/8860620466898300528
[00:20:56.207] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:2:0) after 1m0s
[00:21:56.229] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:2:0)
[00:21:56.229] Timer tick, asking Buildbucket for the build status
[00:21:56.356] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:56.356] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:3:0) after 1m0s
[00:22:56.376] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:3:0)
[00:22:56.376] Timer tick, asking Buildbucket for the build status
[00:22:56.472] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:56.472] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:4:0) after 1m0s
[00:23:56.507] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:4:0)
[00:23:56.507] Timer tick, asking Buildbucket for the build status
[00:23:56.601] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:56.601] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:5:0) after 1m0s
[00:24:56.620] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:5:0)
[00:24:56.620] Timer tick, asking Buildbucket for the build status
[00:24:56.702] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:56.702] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:6:0) after 1m0s
[00:25:56.721] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:6:0)
[00:25:56.721] Timer tick, asking Buildbucket for the build status
[00:25:56.808] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:56.808] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:7:0) after 1m0s
[00:26:56.831] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:7:0)
[00:26:56.832] Timer tick, asking Buildbucket for the build status
[00:26:56.936] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:56.936] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:8:0) after 1m0s
[00:27:56.965] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:8:0)
[00:27:56.965] Timer tick, asking Buildbucket for the build status
[00:27:57.078] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:57.078] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:9:0) after 1m0s
[00:28:57.102] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:9:0)
[00:28:57.102] Timer tick, asking Buildbucket for the build status
[00:28:57.221] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:57.221] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:10:0) after 1m0s
[00:29:57.243] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:10:0)
[00:29:57.243] Timer tick, asking Buildbucket for the build status
[00:29:57.346] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:57.346] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:11:0) after 1m0s
[00:30:57.383] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:11:0)
[00:30:57.383] Timer tick, asking Buildbucket for the build status
[00:30:57.520] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:57.520] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:12:0) after 1m0s
[00:31:57.574] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:12:0)
[00:31:57.574] Timer tick, asking Buildbucket for the build status
[00:31:57.697] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:57.697] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:13:0) after 1m0s
[00:32:57.724] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:13:0)
[00:32:57.724] Timer tick, asking Buildbucket for the build status
[00:32:57.817] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:57.817] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:14:0) after 1m0s
[00:33:57.839] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:14:0)
[00:33:57.839] Timer tick, asking Buildbucket for the build status
[00:33:57.933] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:57.933] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:15:0) after 1m0s
[00:34:57.965] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:15:0)
[00:34:57.965] Timer tick, asking Buildbucket for the build status
[00:34:58.065] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:58.065] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:16:0) after 1m0s
[00:35:58.104] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:16:0)
[00:35:58.104] Timer tick, asking Buildbucket for the build status
[00:35:58.207] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:58.207] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:17:0) after 1m0s
[00:36:58.232] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:17:0)
[00:36:58.232] Timer tick, asking Buildbucket for the build status
[00:36:58.332] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:58.332] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:18:0) after 1m0s
[00:37:58.358] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:18:0)
[00:37:58.358] Timer tick, asking Buildbucket for the build status
[00:37:58.450] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:58.450] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:19:0) after 1m0s
[00:38:58.475] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:19:0)
[00:38:58.475] Timer tick, asking Buildbucket for the build status
[00:38:58.582] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:58.583] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:20:0) after 1m0s
[00:39:58.618] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:20:0)
[00:39:58.618] Timer tick, asking Buildbucket for the build status
[00:39:58.713] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:58.713] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:21:0) after 1m0s
[00:40:58.738] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:21:0)
[00:40:58.738] Timer tick, asking Buildbucket for the build status
[00:40:58.825] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:58.825] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:22:0) after 1m0s
[00:41:58.860] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:22:0)
[00:41:58.860] Timer tick, asking Buildbucket for the build status
[00:41:58.989] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:58.989] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:23:0) after 1m0s
[00:42:59.018] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:23:0)
[00:42:59.018] Timer tick, asking Buildbucket for the build status
[00:42:59.135] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:59.135] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:24:0) after 1m0s
[00:43:59.159] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:24:0)
[00:43:59.159] Timer tick, asking Buildbucket for the build status
[00:43:59.260] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:59.260] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:25:0) after 1m0s
[00:44:59.290] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:25:0)
[00:44:59.290] Timer tick, asking Buildbucket for the build status
[00:44:59.388] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:59.388] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:26:0) after 1m0s
[00:45:59.416] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:26:0)
[00:45:59.416] Timer tick, asking Buildbucket for the build status
[00:45:59.519] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:59.519] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:27:0) after 1m0s
[00:46:59.541] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:27:0)
[00:46:59.541] Timer tick, asking Buildbucket for the build status
[00:46:59.650] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:59.650] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:28:0) after 1m0s
[00:47:59.682] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:28:0)
[00:47:59.682] Timer tick, asking Buildbucket for the build status
[00:47:59.770] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:59.770] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:29:0) after 1m0s
[00:48:59.797] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:29:0)
[00:48:59.797] Timer tick, asking Buildbucket for the build status
[00:48:59.890] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:59.890] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:30:0) after 1m0s
[00:49:59.913] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:30:0)
[00:49:59.913] Timer tick, asking Buildbucket for the build status
[00:50:00.144] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:00.144] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:31:0) after 1m0s
[00:51:00.170] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:31:0)
[00:51:00.170] Timer tick, asking Buildbucket for the build status
[00:51:00.417] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:00.417] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:32:0) after 1m0s
[00:52:00.461] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:32:0)
[00:52:00.461] Timer tick, asking Buildbucket for the build status
[00:52:00.613] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:00.613] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:33:0) after 1m0s
[00:53:00.648] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:33:0)
[00:53:00.648] Timer tick, asking Buildbucket for the build status
[00:53:00.753] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:00.753] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:34:0) after 1m0s
[00:54:00.793] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:34:0)
[00:54:00.793] Timer tick, asking Buildbucket for the build status
[00:54:00.912] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:00.912] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:35:0) after 1m0s
[00:55:00.961] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:35:0)
[00:55:00.961] Timer tick, asking Buildbucket for the build status
[00:55:01.088] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:01.088] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:36:0) after 1m0s
[00:56:01.122] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:36:0)
[00:56:01.122] Timer tick, asking Buildbucket for the build status
[00:56:01.203] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:01.203] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:37:0) after 1m0s
[00:57:01.264] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:37:0)
[00:57:01.264] Timer tick, asking Buildbucket for the build status
[00:57:01.379] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:01.379] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:38:0) after 1m0s
[00:58:01.409] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:38:0)
[00:58:01.409] Timer tick, asking Buildbucket for the build status
[00:58:01.550] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:01.550] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:39:0) after 1m0s
[00:59:01.584] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:39:0)
[00:59:01.584] Timer tick, asking Buildbucket for the build status
[00:59:01.751] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:01.751] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:40:0) after 1m0s
[01:00:01.787] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:40:0)
[01:00:01.787] Timer tick, asking Buildbucket for the build status
[01:00:01.865] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:01.865] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:41:0) after 1m0s
[01:01:01.908] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:41:0)
[01:01:01.908] Timer tick, asking Buildbucket for the build status
[01:01:02.005] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:02.005] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:42:0) after 1m0s
[01:02:02.056] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:42:0)
[01:02:02.056] Timer tick, asking Buildbucket for the build status
[01:02:02.146] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:02.146] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:43:0) after 1m0s
[01:03:02.203] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:43:0)
[01:03:02.203] Timer tick, asking Buildbucket for the build status
[01:03:02.349] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:02.349] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:44:0) after 1m0s
[01:04:02.378] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:44:0)
[01:04:02.378] Timer tick, asking Buildbucket for the build status
[01:04:02.546] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:02.546] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:45:0) after 1m0s
[01:05:02.573] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:45:0)
[01:05:02.573] Timer tick, asking Buildbucket for the build status
[01:05:02.682] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:02.682] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:46:0) after 1m0s
[01:06:02.717] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:46:0)
[01:06:02.717] Timer tick, asking Buildbucket for the build status
[01:06:02.825] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:02.825] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:47:0) after 1m0s
[01:07:02.851] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:47:0)
[01:07:02.851] Timer tick, asking Buildbucket for the build status
[01:07:02.937] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:02.937] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:48:0) after 1m0s
[01:08:02.970] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:48:0)
[01:08:02.970] Timer tick, asking Buildbucket for the build status
[01:08:03.124] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:03.124] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:49:0) after 1m0s
[01:09:03.149] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:49:0)
[01:09:03.149] Timer tick, asking Buildbucket for the build status
[01:09:03.295] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:03.295] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:50:0) after 1m0s
[01:10:03.322] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:50:0)
[01:10:03.322] Timer tick, asking Buildbucket for the build status
[01:10:03.566] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:03.567] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:51:0) after 1m0s
[01:11:03.587] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:51:0)
[01:11:03.587] Timer tick, asking Buildbucket for the build status
[01:11:03.667] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:03.667] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:52:0) after 1m0s
[01:12:03.698] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:52:0)
[01:12:03.698] Timer tick, asking Buildbucket for the build status
[01:12:03.783] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:03.783] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:53:0) after 1m0s
[01:13:03.804] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:53:0)
[01:13:03.804] Timer tick, asking Buildbucket for the build status
[01:13:03.898] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:03.898] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:54:0) after 1m0s
[01:14:03.922] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:54:0)
[01:14:03.922] Timer tick, asking Buildbucket for the build status
[01:14:04.009] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:04.009] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:55:0) after 1m0s
[01:15:04.038] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:55:0)
[01:15:04.038] Timer tick, asking Buildbucket for the build status
[01:15:04.136] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:04.136] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:56:0) after 1m0s
[01:16:04.185] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:56:0)
[01:16:04.186] Timer tick, asking Buildbucket for the build status
[01:16:04.283] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:04.283] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:57:0) after 1m0s
[01:17:04.311] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:57:0)
[01:17:04.311] Timer tick, asking Buildbucket for the build status
[01:17:04.413] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:04.413] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:58:0) after 1m0s
[01:18:04.452] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:58:0)
[01:18:04.452] Timer tick, asking Buildbucket for the build status
[01:18:04.568] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:04.569] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:59:0) after 1m0s
[01:19:04.613] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:59:0)
[01:19:04.613] Timer tick, asking Buildbucket for the build status
[01:19:04.709] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:04.710] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:60:0) after 1m0s
[01:20:04.743] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:60:0)
[01:20:04.743] Timer tick, asking Buildbucket for the build status
[01:20:04.829] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:04.829] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:61:0) after 1m0s
[01:21:04.858] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:61:0)
[01:21:04.858] Timer tick, asking Buildbucket for the build status
[01:21:05.033] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:05.033] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:62:0) after 1m0s
[01:22:05.067] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:62:0)
[01:22:05.067] Timer tick, asking Buildbucket for the build status
[01:22:05.179] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:05.179] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:63:0) after 1m0s
[01:23:05.205] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:63:0)
[01:23:05.205] Timer tick, asking Buildbucket for the build status
[01:23:05.320] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:05.320] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:64:0) after 1m0s
[01:24:05.361] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:64:0)
[01:24:05.361] Timer tick, asking Buildbucket for the build status
[01:24:05.466] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:05.466] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:65:0) after 1m0s
[01:25:05.491] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:65:0)
[01:25:05.491] Timer tick, asking Buildbucket for the build status
[01:25:05.607] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:05.607] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:66:0) after 1m0s
[01:26:05.631] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:66:0)
[01:26:05.631] Timer tick, asking Buildbucket for the build status
[01:26:05.731] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:05.731] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:67:0) after 1m0s
[01:27:05.783] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:67:0)
[01:27:05.783] Timer tick, asking Buildbucket for the build status
[01:27:05.929] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:27:05.929] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:68:0) after 1m0s
[01:28:05.949] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:68:0)
[01:28:05.949] Timer tick, asking Buildbucket for the build status
[01:28:06.054] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:28:06.054] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:69:0) after 1m0s
[01:29:06.103] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:69:0)
[01:29:06.103] Timer tick, asking Buildbucket for the build status
[01:29:06.242] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:29:06.243] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:70:0) after 1m0s
[01:30:06.272] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:70:0)
[01:30:06.272] Timer tick, asking Buildbucket for the build status
[01:30:06.373] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:30:06.373] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:71:0) after 1m0s
[01:31:06.403] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:71:0)
[01:31:06.403] Timer tick, asking Buildbucket for the build status
[01:31:06.500] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:31:06.500] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:72:0) after 1m0s
[01:32:06.525] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:72:0)
[01:32:06.525] Timer tick, asking Buildbucket for the build status
[01:32:06.612] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:32:06.612] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:73:0) after 1m0s
[01:33:06.652] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:73:0)
[01:33:06.652] Timer tick, asking Buildbucket for the build status
[01:33:06.744] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:33:06.744] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:74:0) after 1m0s
[01:34:06.771] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:74:0)
[01:34:06.771] Timer tick, asking Buildbucket for the build status
[01:34:06.851] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:34:06.851] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:75:0) after 1m0s
[01:35:06.905] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:75:0)
[01:35:06.905] Timer tick, asking Buildbucket for the build status
[01:35:06.989] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:35:06.989] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:76:0) after 1m0s
[01:36:07.037] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:76:0)
[01:36:07.037] Timer tick, asking Buildbucket for the build status
[01:36:07.117] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:36:07.117] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:77:0) after 1m0s
[01:37:07.157] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:77:0)
[01:37:07.157] Timer tick, asking Buildbucket for the build status
[01:37:07.239] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:37:07.239] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:78:0) after 1m0s
[01:38:07.267] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:78:0)
[01:38:07.267] Timer tick, asking Buildbucket for the build status
[01:38:07.357] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:38:07.357] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:79:0) after 1m0s
[01:39:07.384] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:79:0)
[01:39:07.384] Timer tick, asking Buildbucket for the build status
[01:39:07.470] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:39:07.470] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:80:0) after 1m0s
[01:40:07.504] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:80:0)
[01:40:07.504] Timer tick, asking Buildbucket for the build status
[01:40:07.582] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:40:07.582] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:81:0) after 1m0s
[01:41:07.615] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:81:0)
[01:41:07.615] Timer tick, asking Buildbucket for the build status
[01:41:07.698] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:41:07.698] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:82:0) after 1m0s
[01:42:07.723] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:82:0)
[01:42:07.723] Timer tick, asking Buildbucket for the build status
[01:42:07.790] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:42:07.790] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:83:0) after 1m0s
[01:43:07.814] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:83:0)
[01:43:07.814] Timer tick, asking Buildbucket for the build status
[01:43:07.883] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:43:07.884] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:84:0) after 1m0s
[01:44:07.907] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:84:0)
[01:44:07.908] Timer tick, asking Buildbucket for the build status
[01:44:07.983] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:44:07.983] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:85:0) after 1m0s
[01:45:08.027] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:85:0)
[01:45:08.027] Timer tick, asking Buildbucket for the build status
[01:45:08.107] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:45:08.107] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:86:0) after 1m0s
[01:46:08.172] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:86:0)
[01:46:08.172] Timer tick, asking Buildbucket for the build status
[01:46:08.265] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:46:08.265] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:87:0) after 1m0s
[01:47:08.310] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:87:0)
[01:47:08.310] Timer tick, asking Buildbucket for the build status
[01:47:08.396] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:47:08.396] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:88:0) after 1m0s
[01:48:08.420] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:88:0)
[01:48:08.420] Timer tick, asking Buildbucket for the build status
[01:48:08.507] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:48:08.507] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:89:0) after 1m0s
[01:49:08.538] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:89:0)
[01:49:08.538] Timer tick, asking Buildbucket for the build status
[01:49:08.708] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:08.708] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:90:0) after 1m0s
[01:50:08.731] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:90:0)
[01:50:08.731] Timer tick, asking Buildbucket for the build status
[01:50:08.830] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:50:08.830] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:91:0) after 1m0s
[01:51:08.879] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:91:0)
[01:51:08.879] Timer tick, asking Buildbucket for the build status
[01:51:08.964] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:51:08.964] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:92:0) after 1m0s
[01:52:08.992] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:92:0)
[01:52:08.992] Timer tick, asking Buildbucket for the build status
[01:52:09.093] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:52:09.093] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:93:0) after 1m0s
[01:53:09.139] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:93:0)
[01:53:09.139] Timer tick, asking Buildbucket for the build status
[01:53:09.237] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:53:09.237] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:94:0) after 1m0s
[01:54:09.264] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:94:0)
[01:54:09.264] Timer tick, asking Buildbucket for the build status
[01:54:09.334] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:54:09.334] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:95:0) after 1m0s
[01:55:09.374] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:95:0)
[01:55:09.374] Timer tick, asking Buildbucket for the build status
[01:55:09.450] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:55:09.450] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:96:0) after 1m0s
[01:56:09.487] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:96:0)
[01:56:09.487] Timer tick, asking Buildbucket for the build status
[01:56:09.579] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:56:09.579] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:97:0) after 1m0s
[01:57:09.616] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:97:0)
[01:57:09.616] Timer tick, asking Buildbucket for the build status
[01:57:09.687] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:57:09.687] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:98:0) after 1m0s
[01:58:09.722] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:98:0)
[01:58:09.722] Timer tick, asking Buildbucket for the build status
[01:58:09.799] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:58:09.799] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:99:0) after 1m0s
[01:59:09.842] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:99:0)
[01:59:09.842] Timer tick, asking Buildbucket for the build status
[01:59:09.939] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:59:09.939] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:100:0) after 1m0s
[02:00:09.988] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:100:0)
[02:00:09.988] Timer tick, asking Buildbucket for the build status
[02:00:10.099] Build 8860620466898300528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:00:10.099] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:101:0) after 1m0s
[02:00:43.262] Received PubSub notification, asking Buildbucket for the build status
[02:00:43.353] Build 8860620466898300528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:10.149] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:101:0)
[02:01:10.149] Timer tick, asking Buildbucket for the build status
[02:01:10.263] Build 8860620466898300528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:10.263] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:103:0) after 1m0s
[02:02:10.293] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:103:0)
[02:02:10.293] Timer tick, asking Buildbucket for the build status
[02:02:10.374] Build 8860620466898300528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:10.374] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:104:0) after 1m0s
[02:03:10.434] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:104:0)
[02:03:10.434] Timer tick, asking Buildbucket for the build status
[02:03:10.535] Build 8860620466898300528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:10.535] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:105:0) after 1m0s
[02:04:10.567] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:105:0)
[02:04:10.567] Timer tick, asking Buildbucket for the build status
[02:04:10.650] Build 8860620466898300528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:10.650] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:106:0) after 1m0s
[02:05:10.675] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:106:0)
[02:05:10.675] Timer tick, asking Buildbucket for the build status
[02:05:10.766] Build 8860620466898300528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:10.766] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:107:0) after 1m0s
[02:06:10.792] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:107:0)
[02:06:10.792] Timer tick, asking Buildbucket for the build status
[02:06:10.873] Build 8860620466898300528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:10.873] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026050528998648448:108:0) after 1m0s
[02:06:48.191] Received PubSub notification, asking Buildbucket for the build status
[02:06:48.285] Build 8860620466898300528: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:06:48.286] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1608257204662029",
"created_by": "project:infra",
"created_ts": "1608250855503757",
"id": "8860620466898300528",
"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\": \"10fd6790788326cb38759544d77a57a3904581d1\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@10fd6790788326cb38759544d77a57a3904581d1\", \"title\": \"10fd6790788326cb38759544d77a57a3904581d1\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/10fd6790788326cb38759544d77a57a3904581d1\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"fea24d0599799dfd3959dbf899e019c7d6b55f4d\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@fea24d0599799dfd3959dbf899e019c7d6b55f4d\", \"title\": \"fea24d0599799dfd3959dbf899e019c7d6b55f4d\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/fea24d0599799dfd3959dbf899e019c7d6b55f4d\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"65c7d780b0c85f3d066e9eb67d331ec9cabd583e\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@65c7d780b0c85f3d066e9eb67d331ec9cabd583e\", \"title\": \"65c7d780b0c85f3d066e9eb67d331ec9cabd583e\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/65c7d780b0c85f3d066e9eb67d331ec9cabd583e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"65c7d780b0c85f3d066e9eb67d331ec9cabd583e\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"65c7d780b0c85f3d066e9eb67d331ec9cabd583e\", \"got_revision_cp\": \"refs/heads/master@{#37173}\"}, \"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-xmlf\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"started_ts": "1608256840194779",
"status": "COMPLETED",
"status_changed_ts": "1608257204959423",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18187",
"builder:infra-continuous-win10-64",
"buildset:commit/git/65c7d780b0c85f3d066e9eb67d331ec9cabd583e",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/65c7d780b0c85f3d066e9eb67d331ec9cabd583e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026050528998648448",
"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/8860620466898300528/+/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:508c0a1c081ca210",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1608257204993606",
"url": "https://luci-milo-dev.appspot.com/b/8860620466898300528",
"utcnow_ts": "1608257208276858"
}
}
[02:06:48.286] Invocation finished in 1h45m54.169485361s with status SUCCEEDED