[08:02:00.098] New invocation is queued and will start shortly
[08:02:01.167] Starting the invocation (attempt 1)
[08:02:01.202] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[08:02:01.202] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[08:02:01.202] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9020313910248744336",
"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\":\"2119bc81f67395e6796ba4b01d03a465fbe1eb57\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@2119bc81f67395e6796ba4b01d03a465fbe1eb57\",\"title\":\"2119bc81f67395e6796ba4b01d03a465fbe1eb57\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/2119bc81f67395e6796ba4b01d03a465fbe1eb57\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"2119bc81f67395e6796ba4b01d03a465fbe1eb57\"}}",
"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:9020313910248744336",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/2119bc81f67395e6796ba4b01d03a465fbe1eb57",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/2119bc81f67395e6796ba4b01d03a465fbe1eb57",
"gitiles_ref:refs/heads/master"
]
}
[08:02:02.603] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:infra",
"created_ts": "1613721721422146",
"id": "8854883848196190704",
"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\": \"2119bc81f67395e6796ba4b01d03a465fbe1eb57\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@2119bc81f67395e6796ba4b01d03a465fbe1eb57\", \"title\": \"2119bc81f67395e6796ba4b01d03a465fbe1eb57\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/2119bc81f67395e6796ba4b01d03a465fbe1eb57\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"2119bc81f67395e6796ba4b01d03a465fbe1eb57\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613721722347685",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18654",
"builder:infra-continuous-win10-64",
"buildset:commit/git/2119bc81f67395e6796ba4b01d03a465fbe1eb57",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/2119bc81f67395e6796ba4b01d03a465fbe1eb57",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020313910248744336",
"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/8854883848196190704/+/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": "1613721722347803",
"url": "https://luci-milo-dev.appspot.com/b/8854883848196190704",
"utcnow_ts": "1613721722589823"
}
}
[08:02:02.603] Task URL: https://luci-milo-dev.appspot.com/b/8854883848196190704
[08:02:02.603] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:2:0) after 1m0s
[08:03:02.654] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:2:0)
[08:03:02.654] Timer tick, asking Buildbucket for the build status
[08:03:02.746] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:03:02.746] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:3:0) after 1m0s
[08:04:02.767] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:3:0)
[08:04:02.768] Timer tick, asking Buildbucket for the build status
[08:04:03.093] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:04:03.093] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:4:0) after 1m0s
[08:05:03.192] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:4:0)
[08:05:03.192] Timer tick, asking Buildbucket for the build status
[08:05:03.300] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:05:03.300] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:5:0) after 1m0s
[08:06:03.402] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:5:0)
[08:06:03.402] Timer tick, asking Buildbucket for the build status
[08:06:03.487] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:06:03.487] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:6:0) after 1m0s
[08:07:03.556] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:6:0)
[08:07:03.556] Timer tick, asking Buildbucket for the build status
[08:07:03.649] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:07:03.649] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:7:0) after 1m0s
[08:08:03.756] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:7:0)
[08:08:03.756] Timer tick, asking Buildbucket for the build status
[08:08:03.922] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:08:03.922] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:8:0) after 1m0s
[08:09:04.004] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:8:0)
[08:09:04.004] Timer tick, asking Buildbucket for the build status
[08:09:04.126] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:09:04.126] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:9:0) after 1m0s
[08:10:04.143] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:9:0)
[08:10:04.143] Timer tick, asking Buildbucket for the build status
[08:10:04.272] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:10:04.272] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:10:0) after 1m0s
[08:11:04.356] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:10:0)
[08:11:04.356] Timer tick, asking Buildbucket for the build status
[08:11:04.472] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:11:04.472] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:11:0) after 1m0s
[08:12:04.510] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:11:0)
[08:12:04.510] Timer tick, asking Buildbucket for the build status
[08:12:04.628] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:12:04.628] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:12:0) after 1m0s
[08:13:04.706] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:12:0)
[08:13:04.706] Timer tick, asking Buildbucket for the build status
[08:13:04.829] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:13:04.829] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:13:0) after 1m0s
[08:14:04.903] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:13:0)
[08:14:04.903] Timer tick, asking Buildbucket for the build status
[08:14:05.069] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:14:05.069] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:14:0) after 1m0s
[08:15:05.095] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:14:0)
[08:15:05.095] Timer tick, asking Buildbucket for the build status
[08:15:05.200] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:15:05.200] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:15:0) after 1m0s
[08:16:05.302] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:15:0)
[08:16:05.302] Timer tick, asking Buildbucket for the build status
[08:16:05.415] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:16:05.415] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:16:0) after 1m0s
[08:17:05.508] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:16:0)
[08:17:05.508] Timer tick, asking Buildbucket for the build status
[08:17:05.613] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:17:05.613] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:17:0) after 1m0s
[08:18:05.669] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:17:0)
[08:18:05.669] Timer tick, asking Buildbucket for the build status
[08:18:05.769] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:18:05.769] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:18:0) after 1m0s
[08:19:05.784] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:18:0)
[08:19:05.784] Timer tick, asking Buildbucket for the build status
[08:19:05.873] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:19:05.873] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:19:0) after 1m0s
[08:20:05.931] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:19:0)
[08:20:05.931] Timer tick, asking Buildbucket for the build status
[08:20:06.024] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:20:06.024] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:20:0) after 1m0s
[08:21:06.103] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:20:0)
[08:21:06.103] Timer tick, asking Buildbucket for the build status
[08:21:06.214] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:21:06.214] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:21:0) after 1m0s
[08:22:06.233] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:21:0)
[08:22:06.233] Timer tick, asking Buildbucket for the build status
[08:22:06.311] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:22:06.311] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:22:0) after 1m0s
[08:23:06.339] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:22:0)
[08:23:06.339] Timer tick, asking Buildbucket for the build status
[08:23:06.435] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:23:06.435] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:23:0) after 1m0s
[08:24:06.561] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:23:0)
[08:24:06.561] Timer tick, asking Buildbucket for the build status
[08:24:06.671] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:24:06.671] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:24:0) after 1m0s
[08:25:06.707] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:24:0)
[08:25:06.707] Timer tick, asking Buildbucket for the build status
[08:25:06.837] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:25:06.837] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:25:0) after 1m0s
[08:26:06.861] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:25:0)
[08:26:06.861] Timer tick, asking Buildbucket for the build status
[08:26:06.969] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:26:06.969] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:26:0) after 1m0s
[08:27:07.030] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:26:0)
[08:27:07.030] Timer tick, asking Buildbucket for the build status
[08:27:07.141] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:27:07.141] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:27:0) after 1m0s
[08:28:07.167] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:27:0)
[08:28:07.167] Timer tick, asking Buildbucket for the build status
[08:28:07.274] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:28:07.274] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:28:0) after 1m0s
[08:29:07.365] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:28:0)
[08:29:07.365] Timer tick, asking Buildbucket for the build status
[08:29:07.484] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:29:07.484] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:29:0) after 1m0s
[08:30:07.504] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:29:0)
[08:30:07.504] Timer tick, asking Buildbucket for the build status
[08:30:07.633] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:30:07.633] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:30:0) after 1m0s
[08:31:07.664] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:30:0)
[08:31:07.665] Timer tick, asking Buildbucket for the build status
[08:31:07.810] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:31:07.810] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:31:0) after 1m0s
[08:32:07.840] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:31:0)
[08:32:07.840] Timer tick, asking Buildbucket for the build status
[08:32:07.961] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:32:07.961] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:32:0) after 1m0s
[08:33:08.074] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:32:0)
[08:33:08.074] Timer tick, asking Buildbucket for the build status
[08:33:08.243] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:33:08.243] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:33:0) after 1m0s
[08:34:08.296] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:33:0)
[08:34:08.296] Timer tick, asking Buildbucket for the build status
[08:34:08.447] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:34:08.447] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:34:0) after 1m0s
[08:35:08.503] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:34:0)
[08:35:08.503] Timer tick, asking Buildbucket for the build status
[08:35:08.628] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:35:08.628] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:35:0) after 1m0s
[08:36:08.658] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:35:0)
[08:36:08.658] Timer tick, asking Buildbucket for the build status
[08:36:08.773] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:36:08.773] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:36:0) after 1m0s
[08:37:08.817] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:36:0)
[08:37:08.817] Timer tick, asking Buildbucket for the build status
[08:37:08.935] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:37:08.935] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:37:0) after 1m0s
[08:38:08.974] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:37:0)
[08:38:08.974] Timer tick, asking Buildbucket for the build status
[08:38:09.156] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:38:09.156] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:38:0) after 1m0s
[08:39:09.222] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:38:0)
[08:39:09.222] Timer tick, asking Buildbucket for the build status
[08:39:09.350] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:39:09.350] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:39:0) after 1m0s
[08:40:09.466] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:39:0)
[08:40:09.466] Timer tick, asking Buildbucket for the build status
[08:40:09.589] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:40:09.589] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:40:0) after 1m0s
[08:41:09.695] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:40:0)
[08:41:09.695] Timer tick, asking Buildbucket for the build status
[08:41:09.836] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:41:09.836] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:41:0) after 1m0s
[08:42:09.895] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:41:0)
[08:42:09.895] Timer tick, asking Buildbucket for the build status
[08:42:10.034] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:42:10.034] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:42:0) after 1m0s
[08:43:10.072] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:42:0)
[08:43:10.072] Timer tick, asking Buildbucket for the build status
[08:43:10.190] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:43:10.190] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:43:0) after 1m0s
[08:44:10.208] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:43:0)
[08:44:10.208] Timer tick, asking Buildbucket for the build status
[08:44:10.322] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:44:10.322] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:44:0) after 1m0s
[08:45:10.358] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:44:0)
[08:45:10.358] Timer tick, asking Buildbucket for the build status
[08:45:10.476] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:45:10.476] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:45:0) after 1m0s
[08:46:10.516] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:45:0)
[08:46:10.516] Timer tick, asking Buildbucket for the build status
[08:46:10.630] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:46:10.630] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:46:0) after 1m0s
[08:47:10.696] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:46:0)
[08:47:10.696] Timer tick, asking Buildbucket for the build status
[08:47:10.843] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:47:10.843] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:47:0) after 1m0s
[08:48:10.916] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:47:0)
[08:48:10.916] Timer tick, asking Buildbucket for the build status
[08:48:11.071] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:48:11.071] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:48:0) after 1m0s
[08:49:11.165] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:48:0)
[08:49:11.165] Timer tick, asking Buildbucket for the build status
[08:49:11.278] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:49:11.278] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:49:0) after 1m0s
[08:50:11.358] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:49:0)
[08:50:11.358] Timer tick, asking Buildbucket for the build status
[08:50:11.503] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:50:11.503] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:50:0) after 1m0s
[08:51:11.596] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:50:0)
[08:51:11.596] Timer tick, asking Buildbucket for the build status
[08:51:11.729] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:51:11.729] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:51:0) after 1m0s
[08:52:11.808] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:51:0)
[08:52:11.808] Timer tick, asking Buildbucket for the build status
[08:52:11.929] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:52:11.929] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:52:0) after 1m0s
[08:53:11.971] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:52:0)
[08:53:11.972] Timer tick, asking Buildbucket for the build status
[08:53:12.118] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:53:12.118] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:53:0) after 1m0s
[08:54:12.160] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:53:0)
[08:54:12.160] Timer tick, asking Buildbucket for the build status
[08:54:12.285] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:54:12.285] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:54:0) after 1m0s
[08:55:12.307] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:54:0)
[08:55:12.307] Timer tick, asking Buildbucket for the build status
[08:55:12.405] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:55:12.405] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:55:0) after 1m0s
[08:56:12.499] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:55:0)
[08:56:12.499] Timer tick, asking Buildbucket for the build status
[08:56:12.592] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:56:12.592] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:56:0) after 1m0s
[08:57:12.699] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:56:0)
[08:57:12.699] Timer tick, asking Buildbucket for the build status
[08:57:12.884] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:57:12.884] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:57:0) after 1m0s
[08:58:12.957] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:57:0)
[08:58:12.957] Timer tick, asking Buildbucket for the build status
[08:58:13.065] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:58:13.065] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:58:0) after 1m0s
[08:59:13.095] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:58:0)
[08:59:13.095] Timer tick, asking Buildbucket for the build status
[08:59:13.234] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:59:13.234] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:59:0) after 1m0s
[09:00:13.258] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:59:0)
[09:00:13.258] Timer tick, asking Buildbucket for the build status
[09:00:13.387] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:00:13.388] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:60:0) after 1m0s
[09:01:13.404] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:60:0)
[09:01:13.404] Timer tick, asking Buildbucket for the build status
[09:01:13.539] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:01:13.539] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:61:0) after 1m0s
[09:02:13.604] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:61:0)
[09:02:13.604] Timer tick, asking Buildbucket for the build status
[09:02:13.728] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:02:13.728] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:62:0) after 1m0s
[09:03:13.752] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:62:0)
[09:03:13.752] Timer tick, asking Buildbucket for the build status
[09:03:13.896] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:03:13.896] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:63:0) after 1m0s
[09:04:13.970] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:63:0)
[09:04:13.970] Timer tick, asking Buildbucket for the build status
[09:04:14.083] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:04:14.083] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:64:0) after 1m0s
[09:05:14.116] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:64:0)
[09:05:14.116] Timer tick, asking Buildbucket for the build status
[09:05:14.234] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:05:14.234] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:65:0) after 1m0s
[09:06:14.266] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:65:0)
[09:06:14.266] Timer tick, asking Buildbucket for the build status
[09:06:14.384] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:06:14.384] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:66:0) after 1m0s
[09:07:14.414] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:66:0)
[09:07:14.414] Timer tick, asking Buildbucket for the build status
[09:07:14.532] Build 8854883848196190704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:07:14.532] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:67:0) after 1m0s
[09:07:25.067] Received PubSub notification, asking Buildbucket for the build status
[09:07:25.166] Build 8854883848196190704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:14.603] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:67:0)
[09:08:14.603] Timer tick, asking Buildbucket for the build status
[09:08:14.703] Build 8854883848196190704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:14.703] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:69:0) after 1m0s
[09:09:14.734] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:69:0)
[09:09:14.734] Timer tick, asking Buildbucket for the build status
[09:09:14.834] Build 8854883848196190704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:14.834] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:70:0) after 1m0s
[09:10:14.913] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:70:0)
[09:10:14.913] Timer tick, asking Buildbucket for the build status
[09:10:15.025] Build 8854883848196190704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:15.025] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:71:0) after 1m0s
[09:11:15.058] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:71:0)
[09:11:15.058] Timer tick, asking Buildbucket for the build status
[09:11:15.480] Build 8854883848196190704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:15.480] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:72:0) after 1m0s
[09:12:15.569] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:72:0)
[09:12:15.569] Timer tick, asking Buildbucket for the build status
[09:12:15.695] Build 8854883848196190704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:15.695] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:73:0) after 1m0s
[09:13:15.759] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:73:0)
[09:13:15.759] Timer tick, asking Buildbucket for the build status
[09:13:15.885] Build 8854883848196190704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:15.885] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020313910248744336:74:0) after 1m0s
[09:13:53.871] Received PubSub notification, asking Buildbucket for the build status
[09:13:53.960] Build 8854883848196190704: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:13:53.960] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1613726029463906",
"created_by": "project:infra",
"created_ts": "1613721721422146",
"id": "8854883848196190704",
"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\": \"2119bc81f67395e6796ba4b01d03a465fbe1eb57\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@2119bc81f67395e6796ba4b01d03a465fbe1eb57\", \"title\": \"2119bc81f67395e6796ba4b01d03a465fbe1eb57\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/2119bc81f67395e6796ba4b01d03a465fbe1eb57\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"2119bc81f67395e6796ba4b01d03a465fbe1eb57\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"2119bc81f67395e6796ba4b01d03a465fbe1eb57\", \"got_revision_cp\": \"refs/heads/master@{#38657}\"}, \"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-ynso\"], \"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\": [\"5707-2446c6f\"], \"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": "1613725643512890",
"status": "COMPLETED",
"status_changed_ts": "1613725643871654",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18654",
"builder:infra-continuous-win10-64",
"buildset:commit/git/2119bc81f67395e6796ba4b01d03a465fbe1eb57",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/2119bc81f67395e6796ba4b01d03a465fbe1eb57",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020313910248744336",
"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/8854883848196190704/+/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:51d220e70b20d810",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1613726029502421",
"url": "https://luci-milo-dev.appspot.com/b/8854883848196190704",
"utcnow_ts": "1613726033952511"
}
}
[09:13:53.960] Invocation finished in 1h11m53.87888842s with status SUCCEEDED