[19:20:10.146] New invocation is queued and will start shortly
[19:20:11.306] Starting the invocation (attempt 1)
[19:20:11.353] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[19:20:11.354] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[19:20:11.354] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9030780491743423888",
"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\":\"8284ce690406bdb68ed7661c3b01b16dda483c5a\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8284ce690406bdb68ed7661c3b01b16dda483c5a\",\"title\":\"8284ce690406bdb68ed7661c3b01b16dda483c5a\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/8284ce690406bdb68ed7661c3b01b16dda483c5a\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"b28491576b0a188e22610dab99522f52ab83ccbd\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b28491576b0a188e22610dab99522f52ab83ccbd\",\"title\":\"b28491576b0a188e22610dab99522f52ab83ccbd\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/b28491576b0a188e22610dab99522f52ab83ccbd\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"66292c0f3d4840de27430abf933a5394aad4f828\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@66292c0f3d4840de27430abf933a5394aad4f828\",\"title\":\"66292c0f3d4840de27430abf933a5394aad4f828\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/66292c0f3d4840de27430abf933a5394aad4f828\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"b6c2b5900948fa6797147077b7fa29bbe19fa7b8\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b6c2b5900948fa6797147077b7fa29bbe19fa7b8\",\"title\":\"b6c2b5900948fa6797147077b7fa29bbe19fa7b8\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/b6c2b5900948fa6797147077b7fa29bbe19fa7b8\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"0d946e53f42e666bd9d81376632cc26fb2d91210\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0d946e53f42e666bd9d81376632cc26fb2d91210\",\"title\":\"0d946e53f42e666bd9d81376632cc26fb2d91210\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/0d946e53f42e666bd9d81376632cc26fb2d91210\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"1696de0cf5bdb4b9562f9c263776032379f2cf20\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1696de0cf5bdb4b9562f9c263776032379f2cf20\",\"title\":\"1696de0cf5bdb4b9562f9c263776032379f2cf20\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/1696de0cf5bdb4b9562f9c263776032379f2cf20\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"4c1f27431ad07cf3f0f3654c4143567a1781d61c\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4c1f27431ad07cf3f0f3654c4143567a1781d61c\",\"title\":\"4c1f27431ad07cf3f0f3654c4143567a1781d61c\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/4c1f27431ad07cf3f0f3654c4143567a1781d61c\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"d22dd271997c478ea7e4f909f4bb8f2f4f5fdba3\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d22dd271997c478ea7e4f909f4bb8f2f4f5fdba3\",\"title\":\"d22dd271997c478ea7e4f909f4bb8f2f4f5fdba3\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/d22dd271997c478ea7e4f909f4bb8f2f4f5fdba3\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"2e3e5f583105e4a8f15757923f3cb89aa63eb56b\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@2e3e5f583105e4a8f15757923f3cb89aa63eb56b\",\"title\":\"2e3e5f583105e4a8f15757923f3cb89aa63eb56b\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/2e3e5f583105e4a8f15757923f3cb89aa63eb56b\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"2e3e5f583105e4a8f15757923f3cb89aa63eb56b\"}}",
"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:9030780491743423888",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/2e3e5f583105e4a8f15757923f3cb89aa63eb56b",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/2e3e5f583105e4a8f15757923f3cb89aa63eb56b",
"gitiles_ref:refs/heads/master"
]
}
[19:20:12.107] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:infra",
"created_ts": "1603740011459568",
"id": "8865350429702675456",
"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\": \"8284ce690406bdb68ed7661c3b01b16dda483c5a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8284ce690406bdb68ed7661c3b01b16dda483c5a\", \"title\": \"8284ce690406bdb68ed7661c3b01b16dda483c5a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/8284ce690406bdb68ed7661c3b01b16dda483c5a\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b28491576b0a188e22610dab99522f52ab83ccbd\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b28491576b0a188e22610dab99522f52ab83ccbd\", \"title\": \"b28491576b0a188e22610dab99522f52ab83ccbd\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b28491576b0a188e22610dab99522f52ab83ccbd\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"66292c0f3d4840de27430abf933a5394aad4f828\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@66292c0f3d4840de27430abf933a5394aad4f828\", \"title\": \"66292c0f3d4840de27430abf933a5394aad4f828\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/66292c0f3d4840de27430abf933a5394aad4f828\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b6c2b5900948fa6797147077b7fa29bbe19fa7b8\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b6c2b5900948fa6797147077b7fa29bbe19fa7b8\", \"title\": \"b6c2b5900948fa6797147077b7fa29bbe19fa7b8\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b6c2b5900948fa6797147077b7fa29bbe19fa7b8\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0d946e53f42e666bd9d81376632cc26fb2d91210\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0d946e53f42e666bd9d81376632cc26fb2d91210\", \"title\": \"0d946e53f42e666bd9d81376632cc26fb2d91210\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0d946e53f42e666bd9d81376632cc26fb2d91210\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"1696de0cf5bdb4b9562f9c263776032379f2cf20\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1696de0cf5bdb4b9562f9c263776032379f2cf20\", \"title\": \"1696de0cf5bdb4b9562f9c263776032379f2cf20\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/1696de0cf5bdb4b9562f9c263776032379f2cf20\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"4c1f27431ad07cf3f0f3654c4143567a1781d61c\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4c1f27431ad07cf3f0f3654c4143567a1781d61c\", \"title\": \"4c1f27431ad07cf3f0f3654c4143567a1781d61c\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/4c1f27431ad07cf3f0f3654c4143567a1781d61c\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"d22dd271997c478ea7e4f909f4bb8f2f4f5fdba3\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d22dd271997c478ea7e4f909f4bb8f2f4f5fdba3\", \"title\": \"d22dd271997c478ea7e4f909f4bb8f2f4f5fdba3\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/d22dd271997c478ea7e4f909f4bb8f2f4f5fdba3\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"2e3e5f583105e4a8f15757923f3cb89aa63eb56b\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@2e3e5f583105e4a8f15757923f3cb89aa63eb56b\", \"title\": \"2e3e5f583105e4a8f15757923f3cb89aa63eb56b\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/2e3e5f583105e4a8f15757923f3cb89aa63eb56b\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"2e3e5f583105e4a8f15757923f3cb89aa63eb56b\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603740011977928",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17927",
"builder:infra-continuous-win10-64",
"buildset:commit/git/2e3e5f583105e4a8f15757923f3cb89aa63eb56b",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/2e3e5f583105e4a8f15757923f3cb89aa63eb56b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030780491743423888",
"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/8865350429702675456/+/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": "1603740011978116",
"url": "https://luci-milo-dev.appspot.com/b/8865350429702675456",
"utcnow_ts": "1603740012097928"
}
}
[19:20:12.107] Task URL: https://luci-milo-dev.appspot.com/b/8865350429702675456
[19:20:12.107] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:2:0) after 1m0s
[19:21:12.190] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:2:0)
[19:21:12.190] Timer tick, asking Buildbucket for the build status
[19:21:12.354] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:21:12.354] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:3:0) after 1m0s
[19:22:12.376] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:3:0)
[19:22:12.376] Timer tick, asking Buildbucket for the build status
[19:22:12.467] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:22:12.467] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:4:0) after 1m0s
[19:23:12.500] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:4:0)
[19:23:12.500] Timer tick, asking Buildbucket for the build status
[19:23:12.661] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:23:12.661] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:5:0) after 1m0s
[19:24:12.698] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:5:0)
[19:24:12.698] Timer tick, asking Buildbucket for the build status
[19:24:12.881] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:24:12.881] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:6:0) after 1m0s
[19:25:12.906] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:6:0)
[19:25:12.906] Timer tick, asking Buildbucket for the build status
[19:25:13.024] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:25:13.024] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:7:0) after 1m0s
[19:26:13.097] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:7:0)
[19:26:13.097] Timer tick, asking Buildbucket for the build status
[19:26:13.216] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:26:13.216] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:8:0) after 1m0s
[19:27:13.304] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:8:0)
[19:27:13.304] Timer tick, asking Buildbucket for the build status
[19:27:13.425] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:27:13.425] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:9:0) after 1m0s
[19:28:13.496] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:9:0)
[19:28:13.496] Timer tick, asking Buildbucket for the build status
[19:28:13.628] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:28:13.628] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:10:0) after 1m0s
[19:29:13.706] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:10:0)
[19:29:13.706] Timer tick, asking Buildbucket for the build status
[19:29:13.878] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:29:13.878] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:11:0) after 1m0s
[19:30:13.903] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:11:0)
[19:30:13.903] Timer tick, asking Buildbucket for the build status
[19:30:14.110] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:30:14.110] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:12:0) after 1m0s
[19:31:14.195] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:12:0)
[19:31:14.195] Timer tick, asking Buildbucket for the build status
[19:31:14.462] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:31:14.462] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:13:0) after 1m0s
[19:32:14.486] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:13:0)
[19:32:14.486] Timer tick, asking Buildbucket for the build status
[19:32:14.595] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:32:14.595] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:14:0) after 1m0s
[19:33:14.706] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:14:0)
[19:33:14.706] Timer tick, asking Buildbucket for the build status
[19:33:14.919] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:33:14.919] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:15:0) after 1m0s
[19:34:14.997] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:15:0)
[19:34:14.997] Timer tick, asking Buildbucket for the build status
[19:34:15.456] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:34:15.456] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:16:0) after 1m0s
[19:35:15.482] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:16:0)
[19:35:15.482] Timer tick, asking Buildbucket for the build status
[19:35:15.618] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:35:15.618] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:17:0) after 1m0s
[19:36:15.642] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:17:0)
[19:36:15.642] Timer tick, asking Buildbucket for the build status
[19:36:15.741] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:36:15.741] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:18:0) after 1m0s
[19:37:15.810] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:18:0)
[19:37:15.810] Timer tick, asking Buildbucket for the build status
[19:37:16.132] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:37:16.132] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:19:0) after 1m0s
[19:38:16.243] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:19:0)
[19:38:16.243] Timer tick, asking Buildbucket for the build status
[19:38:16.374] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:38:16.374] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:20:0) after 1m0s
[19:39:16.396] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:20:0)
[19:39:16.396] Timer tick, asking Buildbucket for the build status
[19:39:16.515] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:39:16.515] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:21:0) after 1m0s
[19:40:16.539] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:21:0)
[19:40:16.539] Timer tick, asking Buildbucket for the build status
[19:40:16.651] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:40:16.651] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:22:0) after 1m0s
[19:41:16.695] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:22:0)
[19:41:16.695] Timer tick, asking Buildbucket for the build status
[19:41:16.814] Build 8865350429702675456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:41:16.814] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:23:0) after 1m0s
[19:42:13.895] Received PubSub notification, asking Buildbucket for the build status
[19:42:14.013] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:16.887] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:23:0)
[19:42:16.887] Timer tick, asking Buildbucket for the build status
[19:42:17.002] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:17.002] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:25:0) after 1m0s
[19:43:17.106] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:25:0)
[19:43:17.106] Timer tick, asking Buildbucket for the build status
[19:43:17.254] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:17.254] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:26:0) after 1m0s
[19:44:17.328] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:26:0)
[19:44:17.328] Timer tick, asking Buildbucket for the build status
[19:44:17.445] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:17.445] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:27:0) after 1m0s
[19:45:17.489] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:27:0)
[19:45:17.489] Timer tick, asking Buildbucket for the build status
[19:45:17.572] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:17.572] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:28:0) after 1m0s
[19:46:17.701] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:28:0)
[19:46:17.701] Timer tick, asking Buildbucket for the build status
[19:46:17.794] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:17.794] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:29:0) after 1m0s
[19:47:17.899] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:29:0)
[19:47:17.899] Timer tick, asking Buildbucket for the build status
[19:47:17.992] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:17.992] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:30:0) after 1m0s
[19:48:18.018] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:30:0)
[19:48:18.018] Timer tick, asking Buildbucket for the build status
[19:48:18.126] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:18.126] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:31:0) after 1m0s
[19:49:18.192] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:31:0)
[19:49:18.192] Timer tick, asking Buildbucket for the build status
[19:49:18.320] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:18.320] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:32:0) after 1m0s
[19:50:18.388] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:32:0)
[19:50:18.388] Timer tick, asking Buildbucket for the build status
[19:50:18.573] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:18.573] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:33:0) after 1m0s
[19:51:18.622] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:33:0)
[19:51:18.622] Timer tick, asking Buildbucket for the build status
[19:51:18.725] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:18.725] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:34:0) after 1m0s
[19:52:18.801] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:34:0)
[19:52:18.801] Timer tick, asking Buildbucket for the build status
[19:52:18.959] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:18.959] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:35:0) after 1m0s
[19:53:19.006] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:35:0)
[19:53:19.006] Timer tick, asking Buildbucket for the build status
[19:53:19.100] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:19.100] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:36:0) after 1m0s
[19:54:19.201] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:36:0)
[19:54:19.201] Timer tick, asking Buildbucket for the build status
[19:54:19.370] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:19.370] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:37:0) after 1m0s
[19:55:19.396] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:37:0)
[19:55:19.396] Timer tick, asking Buildbucket for the build status
[19:55:19.505] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:19.505] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:38:0) after 1m0s
[19:56:19.529] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:38:0)
[19:56:19.529] Timer tick, asking Buildbucket for the build status
[19:56:19.616] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:19.616] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:39:0) after 1m0s
[19:57:19.708] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:39:0)
[19:57:19.708] Timer tick, asking Buildbucket for the build status
[19:57:19.800] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:19.800] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:40:0) after 1m0s
[19:58:19.906] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:40:0)
[19:58:19.906] Timer tick, asking Buildbucket for the build status
[19:58:20.007] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:20.007] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:41:0) after 1m0s
[19:59:20.062] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:41:0)
[19:59:20.062] Timer tick, asking Buildbucket for the build status
[19:59:20.162] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:20.162] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:42:0) after 1m0s
[20:00:20.209] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:42:0)
[20:00:20.209] Timer tick, asking Buildbucket for the build status
[20:00:20.326] Build 8865350429702675456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:20.326] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:43:0) after 1m0s
[20:01:20.403] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030780491743423888:43:0)
[20:01:20.403] Timer tick, asking Buildbucket for the build status
[20:01:20.509] Build 8865350429702675456: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:01:20.509] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1603742478346846",
"created_by": "project:infra",
"created_ts": "1603740011459568",
"id": "8865350429702675456",
"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\": \"8284ce690406bdb68ed7661c3b01b16dda483c5a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8284ce690406bdb68ed7661c3b01b16dda483c5a\", \"title\": \"8284ce690406bdb68ed7661c3b01b16dda483c5a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/8284ce690406bdb68ed7661c3b01b16dda483c5a\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b28491576b0a188e22610dab99522f52ab83ccbd\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b28491576b0a188e22610dab99522f52ab83ccbd\", \"title\": \"b28491576b0a188e22610dab99522f52ab83ccbd\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b28491576b0a188e22610dab99522f52ab83ccbd\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"66292c0f3d4840de27430abf933a5394aad4f828\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@66292c0f3d4840de27430abf933a5394aad4f828\", \"title\": \"66292c0f3d4840de27430abf933a5394aad4f828\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/66292c0f3d4840de27430abf933a5394aad4f828\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b6c2b5900948fa6797147077b7fa29bbe19fa7b8\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b6c2b5900948fa6797147077b7fa29bbe19fa7b8\", \"title\": \"b6c2b5900948fa6797147077b7fa29bbe19fa7b8\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b6c2b5900948fa6797147077b7fa29bbe19fa7b8\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0d946e53f42e666bd9d81376632cc26fb2d91210\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0d946e53f42e666bd9d81376632cc26fb2d91210\", \"title\": \"0d946e53f42e666bd9d81376632cc26fb2d91210\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0d946e53f42e666bd9d81376632cc26fb2d91210\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"1696de0cf5bdb4b9562f9c263776032379f2cf20\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1696de0cf5bdb4b9562f9c263776032379f2cf20\", \"title\": \"1696de0cf5bdb4b9562f9c263776032379f2cf20\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/1696de0cf5bdb4b9562f9c263776032379f2cf20\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"4c1f27431ad07cf3f0f3654c4143567a1781d61c\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4c1f27431ad07cf3f0f3654c4143567a1781d61c\", \"title\": \"4c1f27431ad07cf3f0f3654c4143567a1781d61c\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/4c1f27431ad07cf3f0f3654c4143567a1781d61c\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"d22dd271997c478ea7e4f909f4bb8f2f4f5fdba3\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d22dd271997c478ea7e4f909f4bb8f2f4f5fdba3\", \"title\": \"d22dd271997c478ea7e4f909f4bb8f2f4f5fdba3\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/d22dd271997c478ea7e4f909f4bb8f2f4f5fdba3\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"2e3e5f583105e4a8f15757923f3cb89aa63eb56b\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@2e3e5f583105e4a8f15757923f3cb89aa63eb56b\", \"title\": \"2e3e5f583105e4a8f15757923f3cb89aa63eb56b\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/2e3e5f583105e4a8f15757923f3cb89aa63eb56b\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"2e3e5f583105e4a8f15757923f3cb89aa63eb56b\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"2e3e5f583105e4a8f15757923f3cb89aa63eb56b\", \"got_revision_cp\": \"refs/heads/master@{#35992}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"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-gu0p\"], \"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\": [\"5612-26d29a7\"], \"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": "1603741331157224",
"status": "COMPLETED",
"status_changed_ts": "1603742479196153",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17927",
"builder:infra-continuous-win10-64",
"buildset:commit/git/2e3e5f583105e4a8f15757923f3cb89aa63eb56b",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/2e3e5f583105e4a8f15757923f3cb89aa63eb56b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030780491743423888",
"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/8865350429702675456/+/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:4f7f2c177f29e110",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1603742479250382",
"url": "https://luci-milo-dev.appspot.com/b/8865350429702675456",
"utcnow_ts": "1603742480500334"
}
}
[20:01:20.509] Invocation finished in 41m10.380116946s with status SUCCEEDED