[03:56:58.071] New invocation is queued and will start shortly
[03:56:59.210] Starting the invocation (attempt 1)
[03:56:59.249] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[03:56:59.249] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[03:56:59.249] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9023681414198176000",
"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\":\"3fc914a8a7bab8ec086fe6d1bd3bd16f353c1a9a\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@3fc914a8a7bab8ec086fe6d1bd3bd16f353c1a9a\",\"title\":\"3fc914a8a7bab8ec086fe6d1bd3bd16f353c1a9a\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/3fc914a8a7bab8ec086fe6d1bd3bd16f353c1a9a\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"07a3e0455d249b68d405f7ade8b9952a39b0bfa9\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@07a3e0455d249b68d405f7ade8b9952a39b0bfa9\",\"title\":\"07a3e0455d249b68d405f7ade8b9952a39b0bfa9\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/07a3e0455d249b68d405f7ade8b9952a39b0bfa9\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"c3383e4ed3d504894eaa0d58f06550c149b975da\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c3383e4ed3d504894eaa0d58f06550c149b975da\",\"title\":\"c3383e4ed3d504894eaa0d58f06550c149b975da\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/c3383e4ed3d504894eaa0d58f06550c149b975da\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"c3383e4ed3d504894eaa0d58f06550c149b975da\"}}",
"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:9023681414198176000",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/c3383e4ed3d504894eaa0d58f06550c149b975da",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/c3383e4ed3d504894eaa0d58f06550c149b975da",
"gitiles_ref:refs/heads/master"
]
}
[03:56:59.933] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1610510219381475",
"id": "8858251352160543376",
"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\": \"3fc914a8a7bab8ec086fe6d1bd3bd16f353c1a9a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@3fc914a8a7bab8ec086fe6d1bd3bd16f353c1a9a\", \"title\": \"3fc914a8a7bab8ec086fe6d1bd3bd16f353c1a9a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/3fc914a8a7bab8ec086fe6d1bd3bd16f353c1a9a\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"07a3e0455d249b68d405f7ade8b9952a39b0bfa9\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@07a3e0455d249b68d405f7ade8b9952a39b0bfa9\", \"title\": \"07a3e0455d249b68d405f7ade8b9952a39b0bfa9\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/07a3e0455d249b68d405f7ade8b9952a39b0bfa9\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"c3383e4ed3d504894eaa0d58f06550c149b975da\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c3383e4ed3d504894eaa0d58f06550c149b975da\", \"title\": \"c3383e4ed3d504894eaa0d58f06550c149b975da\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/c3383e4ed3d504894eaa0d58f06550c149b975da\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"c3383e4ed3d504894eaa0d58f06550c149b975da\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610510219833687",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18325",
"builder:infra-continuous-win10-64",
"buildset:commit/git/c3383e4ed3d504894eaa0d58f06550c149b975da",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/c3383e4ed3d504894eaa0d58f06550c149b975da",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023681414198176000",
"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/8858251352160543376/+/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": "1610510219833795",
"url": "https://luci-milo-dev.appspot.com/b/8858251352160543376",
"utcnow_ts": "1610510219925310"
}
}
[03:56:59.933] Task URL: https://luci-milo-dev.appspot.com/b/8858251352160543376
[03:56:59.933] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:2:0) after 1m0s
[03:57:59.956] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:2:0)
[03:57:59.956] Timer tick, asking Buildbucket for the build status
[03:58:00.152] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:58:00.152] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:3:0) after 1m0s
[03:59:00.198] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:3:0)
[03:59:00.198] Timer tick, asking Buildbucket for the build status
[03:59:00.305] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:59:00.305] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:4:0) after 1m0s
[04:00:00.387] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:4:0)
[04:00:00.387] Timer tick, asking Buildbucket for the build status
[04:00:00.484] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:00:00.484] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:5:0) after 1m0s
[04:01:00.500] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:5:0)
[04:01:00.500] Timer tick, asking Buildbucket for the build status
[04:01:00.604] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:01:00.604] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:6:0) after 1m0s
[04:02:00.697] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:6:0)
[04:02:00.697] Timer tick, asking Buildbucket for the build status
[04:02:00.818] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:02:00.818] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:7:0) after 1m0s
[04:03:00.890] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:7:0)
[04:03:00.890] Timer tick, asking Buildbucket for the build status
[04:03:00.996] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:03:00.996] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:8:0) after 1m0s
[04:04:01.112] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:8:0)
[04:04:01.113] Timer tick, asking Buildbucket for the build status
[04:04:01.243] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:04:01.243] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:9:0) after 1m0s
[04:05:01.303] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:9:0)
[04:05:01.303] Timer tick, asking Buildbucket for the build status
[04:05:01.382] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:05:01.382] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:10:0) after 1m0s
[04:06:01.489] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:10:0)
[04:06:01.490] Timer tick, asking Buildbucket for the build status
[04:06:01.565] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:06:01.565] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:11:0) after 1m0s
[04:07:01.596] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:11:0)
[04:07:01.597] Timer tick, asking Buildbucket for the build status
[04:07:01.709] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:07:01.709] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:12:0) after 1m0s
[04:08:01.733] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:12:0)
[04:08:01.733] Timer tick, asking Buildbucket for the build status
[04:08:01.950] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:08:01.950] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:13:0) after 1m0s
[04:09:01.971] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:13:0)
[04:09:01.971] Timer tick, asking Buildbucket for the build status
[04:09:02.045] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:09:02.045] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:14:0) after 1m0s
[04:10:02.081] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:14:0)
[04:10:02.081] Timer tick, asking Buildbucket for the build status
[04:10:02.179] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:10:02.179] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:15:0) after 1m0s
[04:11:02.211] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:15:0)
[04:11:02.211] Timer tick, asking Buildbucket for the build status
[04:11:02.343] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:11:02.343] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:16:0) after 1m0s
[04:12:02.402] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:16:0)
[04:12:02.402] Timer tick, asking Buildbucket for the build status
[04:12:02.509] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:12:02.509] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:17:0) after 1m0s
[04:13:02.533] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:17:0)
[04:13:02.533] Timer tick, asking Buildbucket for the build status
[04:13:02.621] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:13:02.621] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:18:0) after 1m0s
[04:14:02.689] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:18:0)
[04:14:02.689] Timer tick, asking Buildbucket for the build status
[04:14:02.770] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:14:02.770] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:19:0) after 1m0s
[04:15:02.793] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:19:0)
[04:15:02.793] Timer tick, asking Buildbucket for the build status
[04:15:02.878] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:15:02.878] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:20:0) after 1m0s
[04:16:02.908] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:20:0)
[04:16:02.908] Timer tick, asking Buildbucket for the build status
[04:16:03.018] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:16:03.018] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:21:0) after 1m0s
[04:17:03.119] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:21:0)
[04:17:03.119] Timer tick, asking Buildbucket for the build status
[04:17:03.204] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:17:03.204] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:22:0) after 1m0s
[04:18:03.301] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:22:0)
[04:18:03.301] Timer tick, asking Buildbucket for the build status
[04:18:03.391] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:18:03.391] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:23:0) after 1m0s
[04:19:03.419] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:23:0)
[04:19:03.419] Timer tick, asking Buildbucket for the build status
[04:19:03.501] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:19:03.501] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:24:0) after 1m0s
[04:20:03.590] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:24:0)
[04:20:03.590] Timer tick, asking Buildbucket for the build status
[04:20:03.691] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:20:03.691] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:25:0) after 1m0s
[04:21:03.801] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:25:0)
[04:21:03.801] Timer tick, asking Buildbucket for the build status
[04:21:03.934] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:21:03.935] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:26:0) after 1m0s
[04:22:03.988] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:26:0)
[04:22:03.988] Timer tick, asking Buildbucket for the build status
[04:22:04.137] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:22:04.137] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:27:0) after 1m0s
[04:23:04.158] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:27:0)
[04:23:04.158] Timer tick, asking Buildbucket for the build status
[04:23:04.291] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:23:04.291] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:28:0) after 1m0s
[04:24:04.322] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:28:0)
[04:24:04.322] Timer tick, asking Buildbucket for the build status
[04:24:04.424] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:24:04.424] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:29:0) after 1m0s
[04:25:04.489] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:29:0)
[04:25:04.489] Timer tick, asking Buildbucket for the build status
[04:25:04.595] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:25:04.595] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:30:0) after 1m0s
[04:26:04.688] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:30:0)
[04:26:04.689] Timer tick, asking Buildbucket for the build status
[04:26:04.826] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:26:04.826] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:31:0) after 1m0s
[04:27:04.851] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:31:0)
[04:27:04.851] Timer tick, asking Buildbucket for the build status
[04:27:04.944] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:27:04.944] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:32:0) after 1m0s
[04:28:05.001] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:32:0)
[04:28:05.001] Timer tick, asking Buildbucket for the build status
[04:28:05.114] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:28:05.114] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:33:0) after 1m0s
[04:29:05.201] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:33:0)
[04:29:05.201] Timer tick, asking Buildbucket for the build status
[04:29:05.324] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:29:05.325] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:34:0) after 1m0s
[04:30:05.406] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:34:0)
[04:30:05.406] Timer tick, asking Buildbucket for the build status
[04:30:05.489] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:30:05.489] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:35:0) after 1m0s
[04:31:05.520] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:35:0)
[04:31:05.520] Timer tick, asking Buildbucket for the build status
[04:31:05.601] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:31:05.601] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:36:0) after 1m0s
[04:32:05.627] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:36:0)
[04:32:05.627] Timer tick, asking Buildbucket for the build status
[04:32:05.698] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:32:05.698] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:37:0) after 1m0s
[04:33:05.797] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:37:0)
[04:33:05.797] Timer tick, asking Buildbucket for the build status
[04:33:05.868] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:33:05.868] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:38:0) after 1m0s
[04:34:05.898] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:38:0)
[04:34:05.898] Timer tick, asking Buildbucket for the build status
[04:34:05.986] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:34:05.986] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:39:0) after 1m0s
[04:35:06.117] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:39:0)
[04:35:06.118] Timer tick, asking Buildbucket for the build status
[04:35:06.213] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:35:06.213] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:40:0) after 1m0s
[04:36:06.242] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:40:0)
[04:36:06.242] Timer tick, asking Buildbucket for the build status
[04:36:06.325] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:36:06.325] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:41:0) after 1m0s
[04:37:06.345] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:41:0)
[04:37:06.345] Timer tick, asking Buildbucket for the build status
[04:37:06.435] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:37:06.435] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:42:0) after 1m0s
[04:38:06.488] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:42:0)
[04:38:06.488] Timer tick, asking Buildbucket for the build status
[04:38:06.574] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:38:06.574] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:43:0) after 1m0s
[04:39:06.596] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:43:0)
[04:39:06.596] Timer tick, asking Buildbucket for the build status
[04:39:06.701] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:39:06.701] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:44:0) after 1m0s
[04:40:06.805] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:44:0)
[04:40:06.805] Timer tick, asking Buildbucket for the build status
[04:40:06.956] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:40:06.957] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:45:0) after 1m0s
[04:41:07.015] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:45:0)
[04:41:07.015] Timer tick, asking Buildbucket for the build status
[04:41:07.095] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:41:07.095] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:46:0) after 1m0s
[04:42:07.201] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:46:0)
[04:42:07.201] Timer tick, asking Buildbucket for the build status
[04:42:07.297] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:42:07.297] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:47:0) after 1m0s
[04:43:07.391] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:47:0)
[04:43:07.391] Timer tick, asking Buildbucket for the build status
[04:43:07.477] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:43:07.477] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:48:0) after 1m0s
[04:44:07.509] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:48:0)
[04:44:07.509] Timer tick, asking Buildbucket for the build status
[04:44:07.599] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:44:07.599] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:49:0) after 1m0s
[04:45:07.701] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:49:0)
[04:45:07.701] Timer tick, asking Buildbucket for the build status
[04:45:07.769] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:45:07.769] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:50:0) after 1m0s
[04:46:07.806] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:50:0)
[04:46:07.806] Timer tick, asking Buildbucket for the build status
[04:46:07.911] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:46:07.911] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:51:0) after 1m0s
[04:47:08.008] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:51:0)
[04:47:08.008] Timer tick, asking Buildbucket for the build status
[04:47:08.093] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:47:08.093] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:52:0) after 1m0s
[04:48:08.189] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:52:0)
[04:48:08.189] Timer tick, asking Buildbucket for the build status
[04:48:08.344] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:48:08.344] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:53:0) after 1m0s
[04:49:08.373] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:53:0)
[04:49:08.373] Timer tick, asking Buildbucket for the build status
[04:49:08.462] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:49:08.462] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:54:0) after 1m0s
[04:50:08.491] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:54:0)
[04:50:08.491] Timer tick, asking Buildbucket for the build status
[04:50:08.597] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:50:08.597] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:55:0) after 1m0s
[04:51:08.703] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:55:0)
[04:51:08.703] Timer tick, asking Buildbucket for the build status
[04:51:08.776] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:51:08.776] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:56:0) after 1m0s
[04:52:08.800] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:56:0)
[04:52:08.800] Timer tick, asking Buildbucket for the build status
[04:52:08.891] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:52:08.891] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:57:0) after 1m0s
[04:53:09.006] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:57:0)
[04:53:09.006] Timer tick, asking Buildbucket for the build status
[04:53:09.083] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:53:09.083] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:58:0) after 1m0s
[04:54:09.203] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:58:0)
[04:54:09.203] Timer tick, asking Buildbucket for the build status
[04:54:09.367] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:54:09.367] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:59:0) after 1m0s
[04:55:09.396] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:59:0)
[04:55:09.396] Timer tick, asking Buildbucket for the build status
[04:55:09.514] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:55:09.514] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:60:0) after 1m0s
[04:56:09.541] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:60:0)
[04:56:09.541] Timer tick, asking Buildbucket for the build status
[04:56:09.800] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:56:09.800] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:61:0) after 1m0s
[04:57:09.822] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:61:0)
[04:57:09.822] Timer tick, asking Buildbucket for the build status
[04:57:09.996] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:57:09.996] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:62:0) after 1m0s
[04:58:10.042] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:62:0)
[04:58:10.042] Timer tick, asking Buildbucket for the build status
[04:58:10.437] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:58:10.437] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:63:0) after 1m0s
[04:59:10.549] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:63:0)
[04:59:10.549] Timer tick, asking Buildbucket for the build status
[04:59:10.649] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:59:10.649] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:64:0) after 1m0s
[05:00:10.706] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:64:0)
[05:00:10.706] Timer tick, asking Buildbucket for the build status
[05:00:10.904] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:00:10.904] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:65:0) after 1m0s
[05:01:10.992] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:65:0)
[05:01:10.992] Timer tick, asking Buildbucket for the build status
[05:01:11.135] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:01:11.135] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:66:0) after 1m0s
[05:02:11.164] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:66:0)
[05:02:11.164] Timer tick, asking Buildbucket for the build status
[05:02:11.381] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:02:11.381] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:67:0) after 1m0s
[05:03:11.411] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:67:0)
[05:03:11.411] Timer tick, asking Buildbucket for the build status
[05:03:12.088] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:03:12.088] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:68:0) after 1m0s
[05:04:12.215] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:68:0)
[05:04:12.215] Timer tick, asking Buildbucket for the build status
[05:04:12.519] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:04:12.519] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:69:0) after 1m0s
[05:05:12.579] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:69:0)
[05:05:12.579] Timer tick, asking Buildbucket for the build status
[05:05:12.866] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:05:12.866] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:70:0) after 1m0s
[05:06:12.891] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:70:0)
[05:06:12.891] Timer tick, asking Buildbucket for the build status
[05:06:13.106] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:06:13.106] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:71:0) after 1m0s
[05:07:13.128] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:71:0)
[05:07:13.128] Timer tick, asking Buildbucket for the build status
[05:07:13.414] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:07:13.414] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:72:0) after 1m0s
[05:08:13.506] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:72:0)
[05:08:13.506] Timer tick, asking Buildbucket for the build status
[05:08:13.735] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:08:13.735] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:73:0) after 1m0s
[05:09:13.806] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:73:0)
[05:09:13.806] Timer tick, asking Buildbucket for the build status
[05:09:14.053] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:09:14.053] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:74:0) after 1m0s
[05:10:14.111] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:74:0)
[05:10:14.111] Timer tick, asking Buildbucket for the build status
[05:10:14.331] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:10:14.332] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:75:0) after 1m0s
[05:11:14.363] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:75:0)
[05:11:14.363] Timer tick, asking Buildbucket for the build status
[05:11:14.637] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:11:14.637] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:76:0) after 1m0s
[05:12:14.705] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:76:0)
[05:12:14.705] Timer tick, asking Buildbucket for the build status
[05:12:15.043] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:12:15.043] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:77:0) after 1m0s
[05:13:15.121] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:77:0)
[05:13:15.121] Timer tick, asking Buildbucket for the build status
[05:13:15.294] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:13:15.294] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:78:0) after 1m0s
[05:14:15.394] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:78:0)
[05:14:15.394] Timer tick, asking Buildbucket for the build status
[05:14:15.752] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:14:15.752] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:79:0) after 1m0s
[05:15:15.793] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:79:0)
[05:15:15.793] Timer tick, asking Buildbucket for the build status
[05:15:15.930] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:15:15.930] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:80:0) after 1m0s
[05:16:16.005] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:80:0)
[05:16:16.005] Timer tick, asking Buildbucket for the build status
[05:16:16.239] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:16:16.239] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:81:0) after 1m0s
[05:17:16.281] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:81:0)
[05:17:16.281] Timer tick, asking Buildbucket for the build status
[05:17:16.385] Build 8858251352160543376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:17:16.385] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:82:0) after 1m0s
[05:17:54.853] Received PubSub notification, asking Buildbucket for the build status
[05:17:54.932] Build 8858251352160543376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:16.516] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:82:0)
[05:18:16.516] Timer tick, asking Buildbucket for the build status
[05:18:16.682] Build 8858251352160543376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:16.682] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:84:0) after 1m0s
[05:19:16.791] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:84:0)
[05:19:16.791] Timer tick, asking Buildbucket for the build status
[05:19:16.876] Build 8858251352160543376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:16.876] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:85:0) after 1m0s
[05:20:16.902] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:85:0)
[05:20:16.902] Timer tick, asking Buildbucket for the build status
[05:20:16.987] Build 8858251352160543376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:16.987] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:86:0) after 1m0s
[05:21:17.104] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:86:0)
[05:21:17.104] Timer tick, asking Buildbucket for the build status
[05:21:17.181] Build 8858251352160543376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:17.181] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:87:0) after 1m0s
[05:22:17.207] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:87:0)
[05:22:17.207] Timer tick, asking Buildbucket for the build status
[05:22:17.286] Build 8858251352160543376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:17.286] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:88:0) after 1m0s
[05:23:17.391] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:88:0)
[05:23:17.391] Timer tick, asking Buildbucket for the build status
[05:23:17.533] Build 8858251352160543376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:17.533] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:89:0) after 1m0s
[05:24:17.604] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:89:0)
[05:24:17.604] Timer tick, asking Buildbucket for the build status
[05:24:17.675] Build 8858251352160543376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:17.675] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023681414198176000:90:0) after 1m0s
[05:24:33.708] Received PubSub notification, asking Buildbucket for the build status
[05:24:33.790] Build 8858251352160543376: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:24:33.790] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1610515471512985",
"created_by": "project:infra",
"created_ts": "1610510219381475",
"id": "8858251352160543376",
"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\": \"3fc914a8a7bab8ec086fe6d1bd3bd16f353c1a9a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@3fc914a8a7bab8ec086fe6d1bd3bd16f353c1a9a\", \"title\": \"3fc914a8a7bab8ec086fe6d1bd3bd16f353c1a9a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/3fc914a8a7bab8ec086fe6d1bd3bd16f353c1a9a\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"07a3e0455d249b68d405f7ade8b9952a39b0bfa9\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@07a3e0455d249b68d405f7ade8b9952a39b0bfa9\", \"title\": \"07a3e0455d249b68d405f7ade8b9952a39b0bfa9\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/07a3e0455d249b68d405f7ade8b9952a39b0bfa9\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"c3383e4ed3d504894eaa0d58f06550c149b975da\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c3383e4ed3d504894eaa0d58f06550c149b975da\", \"title\": \"c3383e4ed3d504894eaa0d58f06550c149b975da\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/c3383e4ed3d504894eaa0d58f06550c149b975da\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"c3383e4ed3d504894eaa0d58f06550c149b975da\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"c3383e4ed3d504894eaa0d58f06550c149b975da\", \"got_revision_cp\": \"refs/heads/master@{#37611}\"}, \"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-xyor\"], \"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\": [\"5661-c6f6250\"], \"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": "1610515074161923",
"status": "COMPLETED",
"status_changed_ts": "1610515471950437",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18325",
"builder:infra-continuous-win10-64",
"buildset:commit/git/c3383e4ed3d504894eaa0d58f06550c149b975da",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/c3383e4ed3d504894eaa0d58f06550c149b975da",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023681414198176000",
"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/8858251352160543376/+/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:5112b546e810a510",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1610515471976181",
"url": "https://luci-milo-dev.appspot.com/b/8858251352160543376",
"utcnow_ts": "1610515473783204"
}
}
[05:24:33.790] Invocation finished in 1h27m35.735997877s with status SUCCEEDED