[18:42:02.158] New invocation is queued and will start shortly
[18:42:03.280] Starting the invocation (attempt 1)
[18:42:03.328] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[18:42:03.328] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[18:42:03.328] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9024894088056244624",
"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\":\"a87cea4dfa244e6e91ade704052437d22395a513\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a87cea4dfa244e6e91ade704052437d22395a513\",\"title\":\"a87cea4dfa244e6e91ade704052437d22395a513\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/a87cea4dfa244e6e91ade704052437d22395a513\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"a87cea4dfa244e6e91ade704052437d22395a513\"}}",
"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:9024894088056244624",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/a87cea4dfa244e6e91ade704052437d22395a513",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/a87cea4dfa244e6e91ade704052437d22395a513",
"gitiles_ref:refs/heads/master"
]
}
[18:42:03.944] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1609353723487108",
"id": "8859464025999359760",
"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\": \"a87cea4dfa244e6e91ade704052437d22395a513\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a87cea4dfa244e6e91ade704052437d22395a513\", \"title\": \"a87cea4dfa244e6e91ade704052437d22395a513\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/a87cea4dfa244e6e91ade704052437d22395a513\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"a87cea4dfa244e6e91ade704052437d22395a513\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609353723852787",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18243",
"builder:infra-continuous-win10-64",
"buildset:commit/git/a87cea4dfa244e6e91ade704052437d22395a513",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/a87cea4dfa244e6e91ade704052437d22395a513",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024894088056244624",
"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/8859464025999359760/+/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": "1609353723852904",
"url": "https://luci-milo-dev.appspot.com/b/8859464025999359760",
"utcnow_ts": "1609353723936245"
}
}
[18:42:03.944] Task URL: https://luci-milo-dev.appspot.com/b/8859464025999359760
[18:42:03.944] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:2:0) after 1m0s
[18:43:03.994] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:2:0)
[18:43:03.994] Timer tick, asking Buildbucket for the build status
[18:43:04.112] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:43:04.112] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:3:0) after 1m0s
[18:44:04.199] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:3:0)
[18:44:04.199] Timer tick, asking Buildbucket for the build status
[18:44:04.345] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:44:04.345] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:4:0) after 1m0s
[18:45:04.399] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:4:0)
[18:45:04.399] Timer tick, asking Buildbucket for the build status
[18:45:04.516] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:45:04.516] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:5:0) after 1m0s
[18:46:04.604] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:5:0)
[18:46:04.604] Timer tick, asking Buildbucket for the build status
[18:46:04.719] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:46:04.719] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:6:0) after 1m0s
[18:47:04.796] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:6:0)
[18:47:04.796] Timer tick, asking Buildbucket for the build status
[18:47:04.899] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:47:04.899] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:7:0) after 1m0s
[18:48:04.970] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:7:0)
[18:48:04.970] Timer tick, asking Buildbucket for the build status
[18:48:05.087] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:48:05.087] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:8:0) after 1m0s
[18:49:05.210] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:8:0)
[18:49:05.210] Timer tick, asking Buildbucket for the build status
[18:49:05.364] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:49:05.364] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:9:0) after 1m0s
[18:50:05.492] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:9:0)
[18:50:05.492] Timer tick, asking Buildbucket for the build status
[18:50:05.625] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:50:05.625] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:10:0) after 1m0s
[18:51:05.700] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:10:0)
[18:51:05.700] Timer tick, asking Buildbucket for the build status
[18:51:05.800] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:51:05.800] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:11:0) after 1m0s
[18:52:05.894] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:11:0)
[18:52:05.894] Timer tick, asking Buildbucket for the build status
[18:52:06.079] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:52:06.079] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:12:0) after 1m0s
[18:53:06.205] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:12:0)
[18:53:06.205] Timer tick, asking Buildbucket for the build status
[18:53:06.423] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:53:06.423] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:13:0) after 1m0s
[18:54:06.482] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:13:0)
[18:54:06.482] Timer tick, asking Buildbucket for the build status
[18:54:06.580] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:54:06.580] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:14:0) after 1m0s
[18:55:06.692] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:14:0)
[18:55:06.692] Timer tick, asking Buildbucket for the build status
[18:55:06.876] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:55:06.876] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:15:0) after 1m0s
[18:56:07.016] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:15:0)
[18:56:07.016] Timer tick, asking Buildbucket for the build status
[18:56:07.137] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:56:07.137] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:16:0) after 1m0s
[18:57:07.160] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:16:0)
[18:57:07.160] Timer tick, asking Buildbucket for the build status
[18:57:07.270] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:57:07.270] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:17:0) after 1m0s
[18:58:07.292] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:17:0)
[18:58:07.292] Timer tick, asking Buildbucket for the build status
[18:58:07.385] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:58:07.385] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:18:0) after 1m0s
[18:59:07.492] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:18:0)
[18:59:07.492] Timer tick, asking Buildbucket for the build status
[18:59:07.623] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:59:07.623] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:19:0) after 1m0s
[19:00:07.685] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:19:0)
[19:00:07.685] Timer tick, asking Buildbucket for the build status
[19:00:07.785] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:00:07.785] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:20:0) after 1m0s
[19:01:07.896] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:20:0)
[19:01:07.896] Timer tick, asking Buildbucket for the build status
[19:01:08.013] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:01:08.013] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:21:0) after 1m0s
[19:02:08.095] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:21:0)
[19:02:08.095] Timer tick, asking Buildbucket for the build status
[19:02:08.261] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:02:08.261] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:22:0) after 1m0s
[19:03:08.282] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:22:0)
[19:03:08.282] Timer tick, asking Buildbucket for the build status
[19:03:08.379] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:03:08.379] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:23:0) after 1m0s
[19:04:08.488] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:23:0)
[19:04:08.488] Timer tick, asking Buildbucket for the build status
[19:04:08.584] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:04:08.584] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:24:0) after 1m0s
[19:05:08.683] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:24:0)
[19:05:08.683] Timer tick, asking Buildbucket for the build status
[19:05:08.782] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:05:08.782] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:25:0) after 1m0s
[19:06:08.891] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:25:0)
[19:06:08.891] Timer tick, asking Buildbucket for the build status
[19:06:09.061] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:06:09.061] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:26:0) after 1m0s
[19:07:09.084] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:26:0)
[19:07:09.084] Timer tick, asking Buildbucket for the build status
[19:07:09.198] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:07:09.198] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:27:0) after 1m0s
[19:08:09.226] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:27:0)
[19:08:09.226] Timer tick, asking Buildbucket for the build status
[19:08:09.340] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:08:09.340] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:28:0) after 1m0s
[19:09:09.397] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:28:0)
[19:09:09.397] Timer tick, asking Buildbucket for the build status
[19:09:09.486] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:09:09.486] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:29:0) after 1m0s
[19:10:09.553] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:29:0)
[19:10:09.553] Timer tick, asking Buildbucket for the build status
[19:10:09.659] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:10:09.659] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:30:0) after 1m0s
[19:11:09.695] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:30:0)
[19:11:09.695] Timer tick, asking Buildbucket for the build status
[19:11:09.793] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:11:09.793] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:31:0) after 1m0s
[19:12:09.888] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:31:0)
[19:12:09.888] Timer tick, asking Buildbucket for the build status
[19:12:10.010] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:12:10.010] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:32:0) after 1m0s
[19:13:10.043] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:32:0)
[19:13:10.043] Timer tick, asking Buildbucket for the build status
[19:13:10.158] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:13:10.158] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:33:0) after 1m0s
[19:14:10.198] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:33:0)
[19:14:10.198] Timer tick, asking Buildbucket for the build status
[19:14:10.336] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:14:10.336] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:34:0) after 1m0s
[19:15:10.362] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:34:0)
[19:15:10.362] Timer tick, asking Buildbucket for the build status
[19:15:10.483] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:15:10.483] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:35:0) after 1m0s
[19:16:10.593] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:35:0)
[19:16:10.593] Timer tick, asking Buildbucket for the build status
[19:16:10.733] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:16:10.733] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:36:0) after 1m0s
[19:17:10.804] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:36:0)
[19:17:10.804] Timer tick, asking Buildbucket for the build status
[19:17:10.929] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:17:10.929] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:37:0) after 1m0s
[19:18:11.044] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:37:0)
[19:18:11.044] Timer tick, asking Buildbucket for the build status
[19:18:11.163] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:18:11.163] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:38:0) after 1m0s
[19:19:11.206] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:38:0)
[19:19:11.206] Timer tick, asking Buildbucket for the build status
[19:19:11.308] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:19:11.308] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:39:0) after 1m0s
[19:20:11.331] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:39:0)
[19:20:11.331] Timer tick, asking Buildbucket for the build status
[19:20:11.433] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:20:11.433] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:40:0) after 1m0s
[19:21:11.494] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:40:0)
[19:21:11.494] Timer tick, asking Buildbucket for the build status
[19:21:11.598] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:21:11.598] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:41:0) after 1m0s
[19:22:11.685] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:41:0)
[19:22:11.685] Timer tick, asking Buildbucket for the build status
[19:22:11.957] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:22:11.957] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:42:0) after 1m0s
[19:23:11.993] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:42:0)
[19:23:11.993] Timer tick, asking Buildbucket for the build status
[19:23:12.118] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:23:12.118] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:43:0) after 1m0s
[19:24:12.196] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:43:0)
[19:24:12.196] Timer tick, asking Buildbucket for the build status
[19:24:12.323] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:24:12.323] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:44:0) after 1m0s
[19:25:12.414] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:44:0)
[19:25:12.414] Timer tick, asking Buildbucket for the build status
[19:25:12.512] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:25:12.512] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:45:0) after 1m0s
[19:26:12.585] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:45:0)
[19:26:12.585] Timer tick, asking Buildbucket for the build status
[19:26:12.803] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:26:12.803] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:46:0) after 1m0s
[19:27:12.897] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:46:0)
[19:27:12.897] Timer tick, asking Buildbucket for the build status
[19:27:12.992] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:27:12.992] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:47:0) after 1m0s
[19:28:13.016] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:47:0)
[19:28:13.016] Timer tick, asking Buildbucket for the build status
[19:28:13.132] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:28:13.132] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:48:0) after 1m0s
[19:29:13.192] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:48:0)
[19:29:13.192] Timer tick, asking Buildbucket for the build status
[19:29:13.286] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:29:13.286] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:49:0) after 1m0s
[19:30:13.387] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:49:0)
[19:30:13.387] Timer tick, asking Buildbucket for the build status
[19:30:13.491] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:30:13.491] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:50:0) after 1m0s
[19:31:13.527] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:50:0)
[19:31:13.527] Timer tick, asking Buildbucket for the build status
[19:31:13.652] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:31:13.652] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:51:0) after 1m0s
[19:32:13.706] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:51:0)
[19:32:13.706] Timer tick, asking Buildbucket for the build status
[19:32:13.846] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:32:13.846] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:52:0) after 1m0s
[19:33:13.885] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:52:0)
[19:33:13.885] Timer tick, asking Buildbucket for the build status
[19:33:13.990] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:33:13.990] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:53:0) after 1m0s
[19:34:14.099] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:53:0)
[19:34:14.099] Timer tick, asking Buildbucket for the build status
[19:34:14.205] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:34:14.205] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:54:0) after 1m0s
[19:35:14.289] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:54:0)
[19:35:14.289] Timer tick, asking Buildbucket for the build status
[19:35:14.406] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:35:14.406] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:55:0) after 1m0s
[19:36:14.485] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:55:0)
[19:36:14.485] Timer tick, asking Buildbucket for the build status
[19:36:14.569] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:36:14.569] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:56:0) after 1m0s
[19:37:14.597] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:56:0)
[19:37:14.597] Timer tick, asking Buildbucket for the build status
[19:37:14.741] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:37:14.741] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:57:0) after 1m0s
[19:38:14.886] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:57:0)
[19:38:14.886] Timer tick, asking Buildbucket for the build status
[19:38:14.985] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:38:14.985] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:58:0) after 1m0s
[19:39:15.095] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:58:0)
[19:39:15.095] Timer tick, asking Buildbucket for the build status
[19:39:15.182] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:39:15.182] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:59:0) after 1m0s
[19:40:15.286] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:59:0)
[19:40:15.286] Timer tick, asking Buildbucket for the build status
[19:40:15.386] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:40:15.386] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:60:0) after 1m0s
[19:41:15.497] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:60:0)
[19:41:15.497] Timer tick, asking Buildbucket for the build status
[19:41:15.586] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:41:15.586] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:61:0) after 1m0s
[19:42:15.611] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:61:0)
[19:42:15.611] Timer tick, asking Buildbucket for the build status
[19:42:15.774] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:42:15.774] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:62:0) after 1m0s
[19:43:15.884] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:62:0)
[19:43:15.884] Timer tick, asking Buildbucket for the build status
[19:43:15.971] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:43:15.971] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:63:0) after 1m0s
[19:44:15.995] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:63:0)
[19:44:15.995] Timer tick, asking Buildbucket for the build status
[19:44:16.115] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:44:16.115] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:64:0) after 1m0s
[19:45:16.197] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:64:0)
[19:45:16.197] Timer tick, asking Buildbucket for the build status
[19:45:16.308] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:45:16.308] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:65:0) after 1m0s
[19:46:16.330] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:65:0)
[19:46:16.330] Timer tick, asking Buildbucket for the build status
[19:46:16.532] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:46:16.532] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:66:0) after 1m0s
[19:47:16.696] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:66:0)
[19:47:16.696] Timer tick, asking Buildbucket for the build status
[19:47:16.801] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:47:16.801] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:67:0) after 1m0s
[19:48:16.894] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:67:0)
[19:48:16.894] Timer tick, asking Buildbucket for the build status
[19:48:16.984] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:48:16.984] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:68:0) after 1m0s
[19:49:17.102] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:68:0)
[19:49:17.102] Timer tick, asking Buildbucket for the build status
[19:49:17.283] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:49:17.283] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:69:0) after 1m0s
[19:50:17.307] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:69:0)
[19:50:17.307] Timer tick, asking Buildbucket for the build status
[19:50:17.423] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:50:17.423] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:70:0) after 1m0s
[19:51:17.500] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:70:0)
[19:51:17.500] Timer tick, asking Buildbucket for the build status
[19:51:17.616] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:51:17.616] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:71:0) after 1m0s
[19:52:17.693] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:71:0)
[19:52:17.693] Timer tick, asking Buildbucket for the build status
[19:52:17.831] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:52:17.831] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:72:0) after 1m0s
[19:53:17.885] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:72:0)
[19:53:17.885] Timer tick, asking Buildbucket for the build status
[19:53:17.987] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:53:17.987] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:73:0) after 1m0s
[19:54:18.106] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:73:0)
[19:54:18.106] Timer tick, asking Buildbucket for the build status
[19:54:18.243] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:54:18.243] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:74:0) after 1m0s
[19:55:18.348] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:74:0)
[19:55:18.348] Timer tick, asking Buildbucket for the build status
[19:55:18.454] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:55:18.454] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:75:0) after 1m0s
[19:56:18.487] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:75:0)
[19:56:18.487] Timer tick, asking Buildbucket for the build status
[19:56:18.586] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:56:18.586] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:76:0) after 1m0s
[19:57:18.702] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:76:0)
[19:57:18.702] Timer tick, asking Buildbucket for the build status
[19:57:18.807] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:57:18.807] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:77:0) after 1m0s
[19:58:18.885] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:77:0)
[19:58:18.885] Timer tick, asking Buildbucket for the build status
[19:58:18.980] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:58:18.980] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:78:0) after 1m0s
[19:59:19.085] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:78:0)
[19:59:19.085] Timer tick, asking Buildbucket for the build status
[19:59:19.187] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:59:19.187] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:79:0) after 1m0s
[20:00:19.302] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:79:0)
[20:00:19.302] Timer tick, asking Buildbucket for the build status
[20:00:19.422] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:00:19.422] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:80:0) after 1m0s
[20:01:19.496] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:80:0)
[20:01:19.496] Timer tick, asking Buildbucket for the build status
[20:01:19.607] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:01:19.607] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:81:0) after 1m0s
[20:02:19.698] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:81:0)
[20:02:19.698] Timer tick, asking Buildbucket for the build status
[20:02:19.813] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:02:19.813] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:82:0) after 1m0s
[20:03:19.909] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:82:0)
[20:03:19.909] Timer tick, asking Buildbucket for the build status
[20:03:20.013] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:03:20.013] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:83:0) after 1m0s
[20:04:20.092] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:83:0)
[20:04:20.092] Timer tick, asking Buildbucket for the build status
[20:04:20.201] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:04:20.201] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:84:0) after 1m0s
[20:05:20.298] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:84:0)
[20:05:20.298] Timer tick, asking Buildbucket for the build status
[20:05:20.423] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:05:20.423] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:85:0) after 1m0s
[20:06:20.486] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:85:0)
[20:06:20.486] Timer tick, asking Buildbucket for the build status
[20:06:20.602] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:06:20.602] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:86:0) after 1m0s
[20:07:20.624] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:86:0)
[20:07:20.624] Timer tick, asking Buildbucket for the build status
[20:07:20.720] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:07:20.720] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:87:0) after 1m0s
[20:08:20.795] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:87:0)
[20:08:20.795] Timer tick, asking Buildbucket for the build status
[20:08:20.898] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:08:20.898] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:88:0) after 1m0s
[20:09:20.996] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:88:0)
[20:09:20.996] Timer tick, asking Buildbucket for the build status
[20:09:21.103] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:09:21.103] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:89:0) after 1m0s
[20:10:21.190] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:89:0)
[20:10:21.190] Timer tick, asking Buildbucket for the build status
[20:10:21.285] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:10:21.285] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:90:0) after 1m0s
[20:11:21.401] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:90:0)
[20:11:21.401] Timer tick, asking Buildbucket for the build status
[20:11:21.524] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:11:21.524] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:91:0) after 1m0s
[20:12:21.552] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:91:0)
[20:12:21.552] Timer tick, asking Buildbucket for the build status
[20:12:21.704] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:12:21.704] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:92:0) after 1m0s
[20:13:21.784] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:92:0)
[20:13:21.784] Timer tick, asking Buildbucket for the build status
[20:13:21.889] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:13:21.889] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:93:0) after 1m0s
[20:14:21.997] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:93:0)
[20:14:21.997] Timer tick, asking Buildbucket for the build status
[20:14:22.125] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:14:22.125] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:94:0) after 1m0s
[20:15:22.200] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:94:0)
[20:15:22.200] Timer tick, asking Buildbucket for the build status
[20:15:22.315] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:15:22.315] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:95:0) after 1m0s
[20:16:22.340] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:95:0)
[20:16:22.340] Timer tick, asking Buildbucket for the build status
[20:16:22.430] Build 8859464025999359760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:16:22.430] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:96:0) after 1m0s
[20:17:22.491] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:96:0)
[20:17:22.491] Timer tick, asking Buildbucket for the build status
[20:17:22.611] Build 8859464025999359760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:22.611] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:97:0) after 1m0s
[20:17:22.733] Received PubSub notification, asking Buildbucket for the build status
[20:17:22.813] Build 8859464025999359760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:22.701] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:97:0)
[20:18:22.701] Timer tick, asking Buildbucket for the build status
[20:18:22.807] Build 8859464025999359760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:22.807] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:99:0) after 1m0s
[20:19:22.887] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:99:0)
[20:19:22.887] Timer tick, asking Buildbucket for the build status
[20:19:23.043] Build 8859464025999359760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:23.044] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:100:0) after 1m0s
[20:20:23.102] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:100:0)
[20:20:23.102] Timer tick, asking Buildbucket for the build status
[20:20:23.230] Build 8859464025999359760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:23.230] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:101:0) after 1m0s
[20:21:23.261] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:101:0)
[20:21:23.261] Timer tick, asking Buildbucket for the build status
[20:21:23.360] Build 8859464025999359760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:23.360] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:102:0) after 1m0s
[20:22:23.441] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:102:0)
[20:22:23.441] Timer tick, asking Buildbucket for the build status
[20:22:23.543] Build 8859464025999359760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:23.543] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024894088056244624:103:0) after 1m0s
[20:23:06.844] Received PubSub notification, asking Buildbucket for the build status
[20:23:06.941] Build 8859464025999359760: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:23:06.941] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1609359784140717",
"created_by": "project:infra",
"created_ts": "1609353723487108",
"id": "8859464025999359760",
"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\": \"a87cea4dfa244e6e91ade704052437d22395a513\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a87cea4dfa244e6e91ade704052437d22395a513\", \"title\": \"a87cea4dfa244e6e91ade704052437d22395a513\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/a87cea4dfa244e6e91ade704052437d22395a513\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"a87cea4dfa244e6e91ade704052437d22395a513\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"a87cea4dfa244e6e91ade704052437d22395a513\", \"got_revision_cp\": \"refs/heads/master@{#37263}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_044445a3bc7a4bfe795493d7956aa02cd526172de1fa7300f69fe152e82ea631_v2\", \"builder_5de1032f0e9ef497433aad28840ee258c037976023b410f653133cee0ad04e2c_v2\", \"builder_82ed111fed7ea8169e482f00b5f3e5338a65daa41208585de67cb0a736b1d538_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_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-gk94\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"started_ts": "1609359436993883",
"status": "COMPLETED",
"status_changed_ts": "1609359784436806",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18243",
"builder:infra-continuous-win10-64",
"buildset:commit/git/a87cea4dfa244e6e91ade704052437d22395a513",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/a87cea4dfa244e6e91ade704052437d22395a513",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024894088056244624",
"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/8859464025999359760/+/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:50cdc688885ca210",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1609359784448314",
"url": "https://luci-milo-dev.appspot.com/b/8859464025999359760",
"utcnow_ts": "1609359786929507"
}
}
[20:23:06.941] Invocation finished in 1h41m4.799637967s with status SUCCEEDED