[22:41:46.234] New invocation is queued and will start shortly
[22:41:47.306] Starting the invocation (attempt 1)
[22:41:47.402] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[22:41:47.402] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[22:41:47.402] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9030224226276801456",
"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\":\"fa955d0b2753701c19c96982d4200be752788069\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@fa955d0b2753701c19c96982d4200be752788069\",\"title\":\"fa955d0b2753701c19c96982d4200be752788069\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/fa955d0b2753701c19c96982d4200be752788069\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"fa955d0b2753701c19c96982d4200be752788069\"}}",
"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:9030224226276801456",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/fa955d0b2753701c19c96982d4200be752788069",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/fa955d0b2753701c19c96982d4200be752788069",
"gitiles_ref:refs/heads/master"
]
}
[22:41:48.194] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1604270507493570",
"id": "8864794164293771760",
"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\": \"fa955d0b2753701c19c96982d4200be752788069\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@fa955d0b2753701c19c96982d4200be752788069\", \"title\": \"fa955d0b2753701c19c96982d4200be752788069\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/fa955d0b2753701c19c96982d4200be752788069\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"fa955d0b2753701c19c96982d4200be752788069\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604270508029727",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17952",
"builder:infra-continuous-win10-64",
"buildset:commit/git/fa955d0b2753701c19c96982d4200be752788069",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/fa955d0b2753701c19c96982d4200be752788069",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030224226276801456",
"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/8864794164293771760/+/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": "1604270508030006",
"url": "https://luci-milo-dev.appspot.com/b/8864794164293771760",
"utcnow_ts": "1604270508174536"
}
}
[22:41:48.194] Task URL: https://luci-milo-dev.appspot.com/b/8864794164293771760
[22:41:48.194] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:2:0) after 1m0s
[22:42:48.278] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:2:0)
[22:42:48.278] Timer tick, asking Buildbucket for the build status
[22:42:48.361] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:48.361] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:3:0) after 1m0s
[22:43:48.393] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:3:0)
[22:43:48.393] Timer tick, asking Buildbucket for the build status
[22:43:48.497] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:48.497] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:4:0) after 1m0s
[22:44:48.577] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:4:0)
[22:44:48.578] Timer tick, asking Buildbucket for the build status
[22:44:48.677] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:48.677] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:5:0) after 1m0s
[22:45:48.736] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:5:0)
[22:45:48.736] Timer tick, asking Buildbucket for the build status
[22:45:48.848] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:48.848] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:6:0) after 1m0s
[22:46:48.894] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:6:0)
[22:46:48.894] Timer tick, asking Buildbucket for the build status
[22:46:48.997] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:48.998] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:7:0) after 1m0s
[22:47:49.020] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:7:0)
[22:47:49.020] Timer tick, asking Buildbucket for the build status
[22:47:49.174] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:49.174] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:8:0) after 1m0s
[22:48:49.187] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:8:0)
[22:48:49.187] Timer tick, asking Buildbucket for the build status
[22:48:49.287] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:49.287] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:9:0) after 1m0s
[22:49:49.319] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:9:0)
[22:49:49.319] Timer tick, asking Buildbucket for the build status
[22:49:49.400] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:49.400] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:10:0) after 1m0s
[22:50:49.481] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:10:0)
[22:50:49.481] Timer tick, asking Buildbucket for the build status
[22:50:49.556] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:49.556] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:11:0) after 1m0s
[22:51:49.588] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:11:0)
[22:51:49.589] Timer tick, asking Buildbucket for the build status
[22:51:49.668] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:49.668] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:12:0) after 1m0s
[22:52:49.796] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:12:0)
[22:52:49.796] Timer tick, asking Buildbucket for the build status
[22:52:49.913] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:49.913] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:13:0) after 1m0s
[22:53:50.030] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:13:0)
[22:53:50.030] Timer tick, asking Buildbucket for the build status
[22:53:50.124] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:50.124] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:14:0) after 1m0s
[22:54:50.219] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:14:0)
[22:54:50.219] Timer tick, asking Buildbucket for the build status
[22:54:50.340] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:50.340] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:15:0) after 1m0s
[22:55:50.338] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:15:0)
[22:55:50.338] Timer tick, asking Buildbucket for the build status
[22:55:50.412] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:50.412] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:16:0) after 1m0s
[22:56:50.489] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:16:0)
[22:56:50.489] Timer tick, asking Buildbucket for the build status
[22:56:50.562] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:50.562] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:17:0) after 1m0s
[22:57:50.594] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:17:0)
[22:57:50.594] Timer tick, asking Buildbucket for the build status
[22:57:50.692] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:50.692] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:18:0) after 1m0s
[22:58:50.723] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:18:0)
[22:58:50.723] Timer tick, asking Buildbucket for the build status
[22:58:50.815] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:50.815] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:19:0) after 1m0s
[22:59:50.927] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:19:0)
[22:59:50.927] Timer tick, asking Buildbucket for the build status
[22:59:51.069] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:51.069] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:20:0) after 1m0s
[23:00:51.209] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:20:0)
[23:00:51.209] Timer tick, asking Buildbucket for the build status
[23:00:51.318] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:51.318] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:21:0) after 1m0s
[23:01:51.421] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:21:0)
[23:01:51.421] Timer tick, asking Buildbucket for the build status
[23:01:51.519] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:51.519] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:22:0) after 1m0s
[23:02:51.552] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:22:0)
[23:02:51.552] Timer tick, asking Buildbucket for the build status
[23:02:51.659] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:51.659] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:23:0) after 1m0s
[23:03:51.682] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:23:0)
[23:03:51.682] Timer tick, asking Buildbucket for the build status
[23:03:51.780] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:51.780] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:24:0) after 1m0s
[23:04:51.831] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:24:0)
[23:04:51.831] Timer tick, asking Buildbucket for the build status
[23:04:51.906] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:51.906] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:25:0) after 1m0s
[23:05:51.932] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:25:0)
[23:05:51.932] Timer tick, asking Buildbucket for the build status
[23:05:52.025] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:52.025] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:26:0) after 1m0s
[23:06:52.116] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:26:0)
[23:06:52.116] Timer tick, asking Buildbucket for the build status
[23:06:52.183] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:52.183] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:27:0) after 1m0s
[23:07:52.245] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:27:0)
[23:07:52.245] Timer tick, asking Buildbucket for the build status
[23:07:52.314] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:52.314] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:28:0) after 1m0s
[23:08:52.444] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:28:0)
[23:08:52.444] Timer tick, asking Buildbucket for the build status
[23:08:52.622] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:52.622] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:29:0) after 1m0s
[23:09:52.655] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:29:0)
[23:09:52.655] Timer tick, asking Buildbucket for the build status
[23:09:52.736] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:52.736] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:30:0) after 1m0s
[23:10:52.824] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:30:0)
[23:10:52.824] Timer tick, asking Buildbucket for the build status
[23:10:52.903] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:52.903] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:31:0) after 1m0s
[23:11:52.994] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:31:0)
[23:11:52.994] Timer tick, asking Buildbucket for the build status
[23:11:53.079] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:53.079] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:32:0) after 1m0s
[23:12:53.139] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:32:0)
[23:12:53.139] Timer tick, asking Buildbucket for the build status
[23:12:53.215] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:53.215] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:33:0) after 1m0s
[23:13:53.303] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:33:0)
[23:13:53.303] Timer tick, asking Buildbucket for the build status
[23:13:53.407] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:53.407] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:34:0) after 1m0s
[23:14:53.494] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:34:0)
[23:14:53.495] Timer tick, asking Buildbucket for the build status
[23:14:53.580] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:53.580] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:35:0) after 1m0s
[23:15:53.648] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:35:0)
[23:15:53.648] Timer tick, asking Buildbucket for the build status
[23:15:53.775] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:53.775] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:36:0) after 1m0s
[23:16:53.825] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:36:0)
[23:16:53.825] Timer tick, asking Buildbucket for the build status
[23:16:53.926] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:53.926] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:37:0) after 1m0s
[23:17:54.021] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:37:0)
[23:17:54.021] Timer tick, asking Buildbucket for the build status
[23:17:54.091] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:54.091] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:38:0) after 1m0s
[23:18:54.205] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:38:0)
[23:18:54.205] Timer tick, asking Buildbucket for the build status
[23:18:54.288] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:54.288] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:39:0) after 1m0s
[23:19:54.389] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:39:0)
[23:19:54.389] Timer tick, asking Buildbucket for the build status
[23:19:54.475] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:54.475] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:40:0) after 1m0s
[23:20:54.594] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:40:0)
[23:20:54.594] Timer tick, asking Buildbucket for the build status
[23:20:54.663] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:54.664] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:41:0) after 1m0s
[23:21:54.684] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:41:0)
[23:21:54.684] Timer tick, asking Buildbucket for the build status
[23:21:54.755] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:54.755] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:42:0) after 1m0s
[23:22:54.824] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:42:0)
[23:22:54.824] Timer tick, asking Buildbucket for the build status
[23:22:54.915] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:54.915] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:43:0) after 1m0s
[23:23:54.976] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:43:0)
[23:23:54.976] Timer tick, asking Buildbucket for the build status
[23:23:55.062] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:55.062] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:44:0) after 1m0s
[23:24:55.093] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:44:0)
[23:24:55.093] Timer tick, asking Buildbucket for the build status
[23:24:55.212] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:55.212] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:45:0) after 1m0s
[23:25:55.281] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:45:0)
[23:25:55.282] Timer tick, asking Buildbucket for the build status
[23:25:55.369] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:55.369] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:46:0) after 1m0s
[23:26:55.509] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:46:0)
[23:26:55.509] Timer tick, asking Buildbucket for the build status
[23:26:55.596] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:55.596] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:47:0) after 1m0s
[23:27:55.636] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:47:0)
[23:27:55.636] Timer tick, asking Buildbucket for the build status
[23:27:55.711] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:55.711] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:48:0) after 1m0s
[23:28:55.753] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:48:0)
[23:28:55.753] Timer tick, asking Buildbucket for the build status
[23:28:55.832] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:55.832] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:49:0) after 1m0s
[23:29:55.879] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:49:0)
[23:29:55.879] Timer tick, asking Buildbucket for the build status
[23:29:55.944] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:55.944] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:50:0) after 1m0s
[23:30:56.036] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:50:0)
[23:30:56.036] Timer tick, asking Buildbucket for the build status
[23:30:56.144] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:56.144] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:51:0) after 1m0s
[23:31:56.194] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:51:0)
[23:31:56.194] Timer tick, asking Buildbucket for the build status
[23:31:56.278] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:56.278] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:52:0) after 1m0s
[23:32:56.394] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:52:0)
[23:32:56.394] Timer tick, asking Buildbucket for the build status
[23:32:56.461] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:56.461] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:53:0) after 1m0s
[23:33:56.508] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:53:0)
[23:33:56.508] Timer tick, asking Buildbucket for the build status
[23:33:56.593] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:56.593] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:54:0) after 1m0s
[23:34:56.614] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:54:0)
[23:34:56.614] Timer tick, asking Buildbucket for the build status
[23:34:56.721] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:56.721] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:55:0) after 1m0s
[23:35:56.796] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:55:0)
[23:35:56.796] Timer tick, asking Buildbucket for the build status
[23:35:56.933] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:56.933] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:56:0) after 1m0s
[23:36:56.978] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:56:0)
[23:36:56.978] Timer tick, asking Buildbucket for the build status
[23:36:57.102] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:57.102] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:57:0) after 1m0s
[23:37:57.133] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:57:0)
[23:37:57.133] Timer tick, asking Buildbucket for the build status
[23:37:57.214] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:57.214] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:58:0) after 1m0s
[23:38:57.239] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:58:0)
[23:38:57.239] Timer tick, asking Buildbucket for the build status
[23:38:57.316] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:57.316] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:59:0) after 1m0s
[23:39:57.348] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:59:0)
[23:39:57.348] Timer tick, asking Buildbucket for the build status
[23:39:57.544] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:57.544] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:60:0) after 1m0s
[23:40:57.576] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:60:0)
[23:40:57.576] Timer tick, asking Buildbucket for the build status
[23:40:57.657] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:57.657] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:61:0) after 1m0s
[23:41:57.727] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:61:0)
[23:41:57.727] Timer tick, asking Buildbucket for the build status
[23:41:57.810] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:57.810] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:62:0) after 1m0s
[23:42:57.848] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:62:0)
[23:42:57.848] Timer tick, asking Buildbucket for the build status
[23:42:58.020] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:58.020] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:63:0) after 1m0s
[23:43:58.049] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:63:0)
[23:43:58.049] Timer tick, asking Buildbucket for the build status
[23:43:58.131] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:58.131] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:64:0) after 1m0s
[23:44:58.227] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:64:0)
[23:44:58.227] Timer tick, asking Buildbucket for the build status
[23:44:58.305] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:58.305] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:65:0) after 1m0s
[23:45:58.420] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:65:0)
[23:45:58.420] Timer tick, asking Buildbucket for the build status
[23:45:58.521] Build 8864794164293771760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:58.521] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:66:0) after 1m0s
[23:46:13.689] Received PubSub notification, asking Buildbucket for the build status
[23:46:13.810] Build 8864794164293771760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:58.556] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:66:0)
[23:46:58.557] Timer tick, asking Buildbucket for the build status
[23:46:58.651] Build 8864794164293771760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:58.651] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:68:0) after 1m0s
[23:47:58.735] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:68:0)
[23:47:58.735] Timer tick, asking Buildbucket for the build status
[23:47:58.824] Build 8864794164293771760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:58.824] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:69:0) after 1m0s
[23:48:58.850] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:69:0)
[23:48:58.850] Timer tick, asking Buildbucket for the build status
[23:48:58.963] Build 8864794164293771760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:58.963] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:70:0) after 1m0s
[23:49:59.029] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:70:0)
[23:49:59.029] Timer tick, asking Buildbucket for the build status
[23:49:59.128] Build 8864794164293771760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:59.128] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:71:0) after 1m0s
[23:50:59.223] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:71:0)
[23:50:59.223] Timer tick, asking Buildbucket for the build status
[23:50:59.382] Build 8864794164293771760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:59.382] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:72:0) after 1m0s
[23:51:59.489] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:72:0)
[23:51:59.489] Timer tick, asking Buildbucket for the build status
[23:51:59.561] Build 8864794164293771760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:59.561] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:73:0) after 1m0s
[23:52:59.587] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:73:0)
[23:52:59.587] Timer tick, asking Buildbucket for the build status
[23:52:59.666] Build 8864794164293771760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:59.666] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:74:0) after 1m0s
[23:53:59.756] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:74:0)
[23:53:59.756] Timer tick, asking Buildbucket for the build status
[23:53:59.844] Build 8864794164293771760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:59.844] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:75:0) after 1m0s
[23:54:59.972] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:75:0)
[23:54:59.972] Timer tick, asking Buildbucket for the build status
[23:55:00.170] Build 8864794164293771760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:00.170] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:76:0) after 1m0s
[23:56:00.233] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:76:0)
[23:56:00.233] Timer tick, asking Buildbucket for the build status
[23:56:00.327] Build 8864794164293771760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:00.327] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:77:0) after 1m0s
[23:57:00.444] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:77:0)
[23:57:00.444] Timer tick, asking Buildbucket for the build status
[23:57:00.519] Build 8864794164293771760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:00.519] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:78:0) after 1m0s
[23:58:00.641] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:78:0)
[23:58:00.641] Timer tick, asking Buildbucket for the build status
[23:58:00.778] Build 8864794164293771760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:00.778] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:79:0) after 1m0s
[23:59:00.838] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:79:0)
[23:59:00.838] Timer tick, asking Buildbucket for the build status
[23:59:00.936] Build 8864794164293771760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:00.936] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:80:0) after 1m0s
[00:00:01.001] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:80:0)
[00:00:01.001] Timer tick, asking Buildbucket for the build status
[00:00:01.153] Build 8864794164293771760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:01.153] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:81:0) after 1m0s
[00:01:01.187] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:81:0)
[00:01:01.187] Timer tick, asking Buildbucket for the build status
[00:01:01.279] Build 8864794164293771760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:01.279] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:82:0) after 1m0s
[00:02:01.311] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:82:0)
[00:02:01.311] Timer tick, asking Buildbucket for the build status
[00:02:01.419] Build 8864794164293771760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:01.419] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030224226276801456:83:0) after 1m0s
[00:02:17.923] Received PubSub notification, asking Buildbucket for the build status
[00:02:18.008] Build 8864794164293771760: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:02:18.008] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1604275336046036",
"created_by": "project:infra",
"created_ts": "1604270507493570",
"id": "8864794164293771760",
"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\": \"fa955d0b2753701c19c96982d4200be752788069\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@fa955d0b2753701c19c96982d4200be752788069\", \"title\": \"fa955d0b2753701c19c96982d4200be752788069\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/fa955d0b2753701c19c96982d4200be752788069\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"fa955d0b2753701c19c96982d4200be752788069\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"fa955d0b2753701c19c96982d4200be752788069\", \"got_revision_cp\": \"refs/heads/master@{#36124}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_044445a3bc7a4bfe795493d7956aa02cd526172de1fa7300f69fe152e82ea631_v2\", \"builder_5de1032f0e9ef497433aad28840ee258c037976023b410f653133cee0ad04e2c_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"git\", \"goma\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-i7a9\"], \"image\": [\"chrome-win10-1703-20101600-6f576df2987\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5620-d281143\"], \"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": "1604274370502136",
"status": "COMPLETED",
"status_changed_ts": "1604275336599364",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17952",
"builder:infra-continuous-win10-64",
"buildset:commit/git/fa955d0b2753701c19c96982d4200be752788069",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/fa955d0b2753701c19c96982d4200be752788069",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030224226276801456",
"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/8864794164293771760/+/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:4f9ecad1145fcd10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1604275336614571",
"url": "https://luci-milo-dev.appspot.com/b/8864794164293771760",
"utcnow_ts": "1604275338000086"
}
}
[00:02:18.008] Invocation finished in 1h20m31.790479541s with status SUCCEEDED