[21:20:14.781] New invocation is queued and will start shortly
[21:20:15.866] Starting the invocation (attempt 1)
[21:20:15.905] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[21:20:15.905] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[21:20:15.905] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9038473679286318496",
"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\":\"481b158a4d6e45f10a1952a2642b8ef1d9c76de9\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@481b158a4d6e45f10a1952a2642b8ef1d9c76de9\",\"title\":\"481b158a4d6e45f10a1952a2642b8ef1d9c76de9\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/481b158a4d6e45f10a1952a2642b8ef1d9c76de9\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"481b158a4d6e45f10a1952a2642b8ef1d9c76de9\"}}",
"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:9038473679286318496",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/481b158a4d6e45f10a1952a2642b8ef1d9c76de9",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/481b158a4d6e45f10a1952a2642b8ef1d9c76de9",
"gitiles_ref:refs/heads/master"
]
}
[21:20:16.875] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1596403216023485",
"id": "8873043617313360288",
"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\": \"481b158a4d6e45f10a1952a2642b8ef1d9c76de9\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@481b158a4d6e45f10a1952a2642b8ef1d9c76de9\", \"title\": \"481b158a4d6e45f10a1952a2642b8ef1d9c76de9\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/481b158a4d6e45f10a1952a2642b8ef1d9c76de9\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"481b158a4d6e45f10a1952a2642b8ef1d9c76de9\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1596403216695176",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17418",
"builder:infra-continuous-win10-64",
"buildset:commit/git/481b158a4d6e45f10a1952a2642b8ef1d9c76de9",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/481b158a4d6e45f10a1952a2642b8ef1d9c76de9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9038473679286318496",
"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/8873043617313360288/+/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": "1596403216695303",
"url": "https://luci-milo-dev.appspot.com/b/8873043617313360288",
"utcnow_ts": "1596403216867311"
}
}
[21:20:16.875] Task URL: https://luci-milo-dev.appspot.com/b/8873043617313360288
[21:20:16.875] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:2:0) after 1m0s
[21:21:16.900] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:2:0)
[21:21:16.900] Timer tick, asking Buildbucket for the build status
[21:21:17.017] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:21:17.017] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:3:0) after 1m0s
[21:22:17.098] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:3:0)
[21:22:17.098] Timer tick, asking Buildbucket for the build status
[21:22:17.190] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:22:17.190] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:4:0) after 1m0s
[21:23:17.297] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:4:0)
[21:23:17.297] Timer tick, asking Buildbucket for the build status
[21:23:17.382] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:23:17.382] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:5:0) after 1m0s
[21:24:17.412] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:5:0)
[21:24:17.412] Timer tick, asking Buildbucket for the build status
[21:24:17.503] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:24:17.503] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:6:0) after 1m0s
[21:25:17.582] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:6:0)
[21:25:17.582] Timer tick, asking Buildbucket for the build status
[21:25:17.687] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:25:17.687] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:7:0) after 1m0s
[21:26:17.708] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:7:0)
[21:26:17.708] Timer tick, asking Buildbucket for the build status
[21:26:17.857] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:26:17.857] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:8:0) after 1m0s
[21:27:17.903] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:8:0)
[21:27:17.903] Timer tick, asking Buildbucket for the build status
[21:27:17.989] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:27:17.989] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:9:0) after 1m0s
[21:28:18.019] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:9:0)
[21:28:18.019] Timer tick, asking Buildbucket for the build status
[21:28:18.114] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:28:18.114] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:10:0) after 1m0s
[21:29:18.166] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:10:0)
[21:29:18.166] Timer tick, asking Buildbucket for the build status
[21:29:18.259] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:18.259] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:11:0) after 1m0s
[21:30:18.287] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:11:0)
[21:30:18.287] Timer tick, asking Buildbucket for the build status
[21:30:18.387] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:18.387] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:12:0) after 1m0s
[21:31:18.417] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:12:0)
[21:31:18.417] Timer tick, asking Buildbucket for the build status
[21:31:18.540] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:18.540] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:13:0) after 1m0s
[21:32:18.572] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:13:0)
[21:32:18.572] Timer tick, asking Buildbucket for the build status
[21:32:18.651] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:18.651] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:14:0) after 1m0s
[21:33:18.679] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:14:0)
[21:33:18.679] Timer tick, asking Buildbucket for the build status
[21:33:18.813] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:18.813] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:15:0) after 1m0s
[21:34:18.904] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:15:0)
[21:34:18.904] Timer tick, asking Buildbucket for the build status
[21:34:19.000] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:19.000] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:16:0) after 1m0s
[21:35:19.085] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:16:0)
[21:35:19.085] Timer tick, asking Buildbucket for the build status
[21:35:19.212] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:35:19.212] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:17:0) after 1m0s
[21:36:19.268] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:17:0)
[21:36:19.268] Timer tick, asking Buildbucket for the build status
[21:36:19.477] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:19.477] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:18:0) after 1m0s
[21:37:19.505] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:18:0)
[21:37:19.505] Timer tick, asking Buildbucket for the build status
[21:37:19.589] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:19.589] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:19:0) after 1m0s
[21:38:19.705] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:19:0)
[21:38:19.705] Timer tick, asking Buildbucket for the build status
[21:38:19.885] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:19.885] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:20:0) after 1m0s
[21:39:20.006] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:20:0)
[21:39:20.006] Timer tick, asking Buildbucket for the build status
[21:39:20.126] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:20.127] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:21:0) after 1m0s
[21:40:20.174] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:21:0)
[21:40:20.174] Timer tick, asking Buildbucket for the build status
[21:40:20.303] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:20.303] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:22:0) after 1m0s
[21:41:20.410] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:22:0)
[21:41:20.410] Timer tick, asking Buildbucket for the build status
[21:41:20.507] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:20.507] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:23:0) after 1m0s
[21:42:20.542] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:23:0)
[21:42:20.543] Timer tick, asking Buildbucket for the build status
[21:42:20.677] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:20.677] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:24:0) after 1m0s
[21:43:20.706] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:24:0)
[21:43:20.706] Timer tick, asking Buildbucket for the build status
[21:43:20.805] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:20.805] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:25:0) after 1m0s
[21:44:20.831] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:25:0)
[21:44:20.831] Timer tick, asking Buildbucket for the build status
[21:44:20.932] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:20.932] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:26:0) after 1m0s
[21:45:21.000] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:26:0)
[21:45:21.000] Timer tick, asking Buildbucket for the build status
[21:45:21.117] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:21.117] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:27:0) after 1m0s
[21:46:21.151] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:27:0)
[21:46:21.151] Timer tick, asking Buildbucket for the build status
[21:46:21.244] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:46:21.244] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:28:0) after 1m0s
[21:47:21.301] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:28:0)
[21:47:21.302] Timer tick, asking Buildbucket for the build status
[21:47:21.408] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:21.408] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:29:0) after 1m0s
[21:48:21.473] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:29:0)
[21:48:21.473] Timer tick, asking Buildbucket for the build status
[21:48:21.570] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:21.570] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:30:0) after 1m0s
[21:49:21.592] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:30:0)
[21:49:21.592] Timer tick, asking Buildbucket for the build status
[21:49:21.676] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:21.676] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:31:0) after 1m0s
[21:50:21.708] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:31:0)
[21:50:21.708] Timer tick, asking Buildbucket for the build status
[21:50:21.864] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:21.864] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:32:0) after 1m0s
[21:51:21.891] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:32:0)
[21:51:21.891] Timer tick, asking Buildbucket for the build status
[21:51:21.968] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:21.968] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:33:0) after 1m0s
[21:52:22.006] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:33:0)
[21:52:22.006] Timer tick, asking Buildbucket for the build status
[21:52:22.120] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:22.120] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:34:0) after 1m0s
[21:53:22.183] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:34:0)
[21:53:22.183] Timer tick, asking Buildbucket for the build status
[21:53:22.270] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:22.270] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:35:0) after 1m0s
[21:54:22.298] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:35:0)
[21:54:22.299] Timer tick, asking Buildbucket for the build status
[21:54:22.380] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:22.380] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:36:0) after 1m0s
[21:55:22.415] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:36:0)
[21:55:22.415] Timer tick, asking Buildbucket for the build status
[21:55:22.535] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:22.535] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:37:0) after 1m0s
[21:56:22.568] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:37:0)
[21:56:22.568] Timer tick, asking Buildbucket for the build status
[21:56:22.759] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:22.759] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:38:0) after 1m0s
[21:57:22.794] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:38:0)
[21:57:22.794] Timer tick, asking Buildbucket for the build status
[21:57:22.900] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:22.900] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:39:0) after 1m0s
[21:58:22.972] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:39:0)
[21:58:22.972] Timer tick, asking Buildbucket for the build status
[21:58:23.103] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:23.103] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:40:0) after 1m0s
[21:59:23.129] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:40:0)
[21:59:23.129] Timer tick, asking Buildbucket for the build status
[21:59:23.253] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:23.253] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:41:0) after 1m0s
[22:00:23.359] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:41:0)
[22:00:23.359] Timer tick, asking Buildbucket for the build status
[22:00:23.556] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:23.556] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:42:0) after 1m0s
[22:01:23.581] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:42:0)
[22:01:23.581] Timer tick, asking Buildbucket for the build status
[22:01:23.673] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:23.673] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:43:0) after 1m0s
[22:02:23.699] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:43:0)
[22:02:23.699] Timer tick, asking Buildbucket for the build status
[22:02:23.836] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:23.836] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:44:0) after 1m0s
[22:03:23.885] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:44:0)
[22:03:23.885] Timer tick, asking Buildbucket for the build status
[22:03:23.973] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:23.973] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:45:0) after 1m0s
[22:04:24.009] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:45:0)
[22:04:24.009] Timer tick, asking Buildbucket for the build status
[22:04:24.118] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:24.118] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:46:0) after 1m0s
[22:05:24.174] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:46:0)
[22:05:24.174] Timer tick, asking Buildbucket for the build status
[22:05:24.323] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:24.323] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:47:0) after 1m0s
[22:06:24.415] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:47:0)
[22:06:24.415] Timer tick, asking Buildbucket for the build status
[22:06:24.506] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:24.506] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:48:0) after 1m0s
[22:07:24.531] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:48:0)
[22:07:24.531] Timer tick, asking Buildbucket for the build status
[22:07:24.621] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:24.621] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:49:0) after 1m0s
[22:08:24.749] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:49:0)
[22:08:24.749] Timer tick, asking Buildbucket for the build status
[22:08:24.857] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:24.857] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:50:0) after 1m0s
[22:09:24.986] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:50:0)
[22:09:24.986] Timer tick, asking Buildbucket for the build status
[22:09:25.095] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:25.095] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:51:0) after 1m0s
[22:10:25.122] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:51:0)
[22:10:25.122] Timer tick, asking Buildbucket for the build status
[22:10:25.267] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:25.267] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:52:0) after 1m0s
[22:11:25.296] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:52:0)
[22:11:25.296] Timer tick, asking Buildbucket for the build status
[22:11:25.400] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:25.400] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:53:0) after 1m0s
[22:12:25.511] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:53:0)
[22:12:25.511] Timer tick, asking Buildbucket for the build status
[22:12:25.616] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:25.616] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:54:0) after 1m0s
[22:13:25.644] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:54:0)
[22:13:25.644] Timer tick, asking Buildbucket for the build status
[22:13:25.742] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:25.742] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:55:0) after 1m0s
[22:14:25.775] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:55:0)
[22:14:25.775] Timer tick, asking Buildbucket for the build status
[22:14:25.961] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:25.961] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:56:0) after 1m0s
[22:15:25.997] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:56:0)
[22:15:25.997] Timer tick, asking Buildbucket for the build status
[22:15:26.129] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:26.129] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:57:0) after 1m0s
[22:16:26.184] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:57:0)
[22:16:26.184] Timer tick, asking Buildbucket for the build status
[22:16:26.369] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:26.369] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:58:0) after 1m0s
[22:17:26.399] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:58:0)
[22:17:26.399] Timer tick, asking Buildbucket for the build status
[22:17:26.500] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:26.500] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:59:0) after 1m0s
[22:18:26.538] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:59:0)
[22:18:26.538] Timer tick, asking Buildbucket for the build status
[22:18:26.673] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:26.673] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:60:0) after 1m0s
[22:19:26.707] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:60:0)
[22:19:26.707] Timer tick, asking Buildbucket for the build status
[22:19:26.808] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:26.808] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:61:0) after 1m0s
[22:20:26.900] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:61:0)
[22:20:26.900] Timer tick, asking Buildbucket for the build status
[22:20:27.025] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:27.025] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:62:0) after 1m0s
[22:21:27.079] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:62:0)
[22:21:27.079] Timer tick, asking Buildbucket for the build status
[22:21:27.177] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:27.177] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:63:0) after 1m0s
[22:22:27.273] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:63:0)
[22:22:27.273] Timer tick, asking Buildbucket for the build status
[22:22:27.367] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:27.367] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:64:0) after 1m0s
[22:23:27.455] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:64:0)
[22:23:27.455] Timer tick, asking Buildbucket for the build status
[22:23:27.561] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:27.561] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:65:0) after 1m0s
[22:24:27.586] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:65:0)
[22:24:27.586] Timer tick, asking Buildbucket for the build status
[22:24:27.704] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:27.704] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:66:0) after 1m0s
[22:25:27.736] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:66:0)
[22:25:27.736] Timer tick, asking Buildbucket for the build status
[22:25:27.980] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:27.980] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:67:0) after 1m0s
[22:26:28.066] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:67:0)
[22:26:28.066] Timer tick, asking Buildbucket for the build status
[22:26:28.265] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:28.265] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:68:0) after 1m0s
[22:27:28.303] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:68:0)
[22:27:28.303] Timer tick, asking Buildbucket for the build status
[22:27:28.397] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:28.397] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:69:0) after 1m0s
[22:28:28.430] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:69:0)
[22:28:28.430] Timer tick, asking Buildbucket for the build status
[22:28:28.529] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:28.529] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:70:0) after 1m0s
[22:29:28.597] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:70:0)
[22:29:28.597] Timer tick, asking Buildbucket for the build status
[22:29:28.680] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:28.680] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:71:0) after 1m0s
[22:30:28.721] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:71:0)
[22:30:28.722] Timer tick, asking Buildbucket for the build status
[22:30:28.883] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:28.883] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:72:0) after 1m0s
[22:31:28.920] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:72:0)
[22:31:28.920] Timer tick, asking Buildbucket for the build status
[22:31:29.022] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:29.022] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:73:0) after 1m0s
[22:32:29.073] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:73:0)
[22:32:29.073] Timer tick, asking Buildbucket for the build status
[22:32:29.174] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:29.174] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:74:0) after 1m0s
[22:33:29.286] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:74:0)
[22:33:29.286] Timer tick, asking Buildbucket for the build status
[22:33:29.449] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:29.449] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:75:0) after 1m0s
[22:34:29.475] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:75:0)
[22:34:29.475] Timer tick, asking Buildbucket for the build status
[22:34:29.565] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:29.565] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:76:0) after 1m0s
[22:35:29.596] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:76:0)
[22:35:29.596] Timer tick, asking Buildbucket for the build status
[22:35:29.721] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:29.721] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:77:0) after 1m0s
[22:36:29.785] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:77:0)
[22:36:29.785] Timer tick, asking Buildbucket for the build status
[22:36:29.877] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:29.877] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:78:0) after 1m0s
[22:37:29.994] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:78:0)
[22:37:29.994] Timer tick, asking Buildbucket for the build status
[22:37:30.106] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:30.106] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:79:0) after 1m0s
[22:38:30.167] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:79:0)
[22:38:30.167] Timer tick, asking Buildbucket for the build status
[22:38:30.356] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:30.356] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:80:0) after 1m0s
[22:39:30.431] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:80:0)
[22:39:30.431] Timer tick, asking Buildbucket for the build status
[22:39:30.512] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:30.512] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:81:0) after 1m0s
[22:40:30.574] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:81:0)
[22:40:30.574] Timer tick, asking Buildbucket for the build status
[22:40:30.789] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:30.789] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:82:0) after 1m0s
[22:41:30.776] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:82:0)
[22:41:30.776] Timer tick, asking Buildbucket for the build status
[22:41:30.884] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:30.884] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:83:0) after 1m0s
[22:42:30.886] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:83:0)
[22:42:30.886] Timer tick, asking Buildbucket for the build status
[22:42:30.970] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:30.970] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:84:0) after 1m0s
[22:43:30.957] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:84:0)
[22:43:30.957] Timer tick, asking Buildbucket for the build status
[22:43:31.050] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:31.050] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:85:0) after 1m0s
[22:44:31.178] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:85:0)
[22:44:31.178] Timer tick, asking Buildbucket for the build status
[22:44:31.287] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:31.287] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:86:0) after 1m0s
[22:45:31.384] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:86:0)
[22:45:31.384] Timer tick, asking Buildbucket for the build status
[22:45:31.523] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:31.523] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:87:0) after 1m0s
[22:46:31.573] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:87:0)
[22:46:31.573] Timer tick, asking Buildbucket for the build status
[22:46:31.688] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:31.688] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:88:0) after 1m0s
[22:47:31.714] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:88:0)
[22:47:31.714] Timer tick, asking Buildbucket for the build status
[22:47:31.851] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:31.851] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:89:0) after 1m0s
[22:48:31.878] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:89:0)
[22:48:31.878] Timer tick, asking Buildbucket for the build status
[22:48:31.992] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:31.992] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:90:0) after 1m0s
[22:49:32.114] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:90:0)
[22:49:32.114] Timer tick, asking Buildbucket for the build status
[22:49:32.244] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:32.244] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:91:0) after 1m0s
[22:50:32.276] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:91:0)
[22:50:32.276] Timer tick, asking Buildbucket for the build status
[22:50:32.432] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:32.432] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:92:0) after 1m0s
[22:51:32.459] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:92:0)
[22:51:32.459] Timer tick, asking Buildbucket for the build status
[22:51:32.569] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:32.569] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:93:0) after 1m0s
[22:52:32.597] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:93:0)
[22:52:32.597] Timer tick, asking Buildbucket for the build status
[22:52:32.709] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:32.709] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:94:0) after 1m0s
[22:53:32.795] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:94:0)
[22:53:32.795] Timer tick, asking Buildbucket for the build status
[22:53:32.895] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:32.895] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:95:0) after 1m0s
[22:54:32.967] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:95:0)
[22:54:32.967] Timer tick, asking Buildbucket for the build status
[22:54:33.089] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:33.089] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:96:0) after 1m0s
[22:55:33.159] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:96:0)
[22:55:33.159] Timer tick, asking Buildbucket for the build status
[22:55:33.325] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:33.325] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:97:0) after 1m0s
[22:56:33.355] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:97:0)
[22:56:33.355] Timer tick, asking Buildbucket for the build status
[22:56:33.457] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:33.457] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:98:0) after 1m0s
[22:57:33.501] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:98:0)
[22:57:33.501] Timer tick, asking Buildbucket for the build status
[22:57:33.596] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:33.596] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:99:0) after 1m0s
[22:58:33.630] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:99:0)
[22:58:33.630] Timer tick, asking Buildbucket for the build status
[22:58:33.728] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:33.728] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:100:0) after 1m0s
[22:59:33.786] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:100:0)
[22:59:33.786] Timer tick, asking Buildbucket for the build status
[22:59:33.898] Build 8873043617313360288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:33.899] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:101:0) after 1m0s
[23:00:29.669] Received PubSub notification, asking Buildbucket for the build status
[23:00:29.776] Build 8873043617313360288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:33.971] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:101:0)
[23:00:33.971] Timer tick, asking Buildbucket for the build status
[23:00:34.063] Build 8873043617313360288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:34.063] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:103:0) after 1m0s
[23:01:34.098] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:103:0)
[23:01:34.098] Timer tick, asking Buildbucket for the build status
[23:01:34.233] Build 8873043617313360288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:34.233] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:104:0) after 1m0s
[23:02:34.287] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:104:0)
[23:02:34.287] Timer tick, asking Buildbucket for the build status
[23:02:34.367] Build 8873043617313360288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:34.367] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:105:0) after 1m0s
[23:03:34.402] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:105:0)
[23:03:34.402] Timer tick, asking Buildbucket for the build status
[23:03:34.571] Build 8873043617313360288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:34.571] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:106:0) after 1m0s
[23:04:34.597] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:106:0)
[23:04:34.597] Timer tick, asking Buildbucket for the build status
[23:04:34.695] Build 8873043617313360288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:34.695] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:107:0) after 1m0s
[23:05:34.776] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:107:0)
[23:05:34.776] Timer tick, asking Buildbucket for the build status
[23:05:34.906] Build 8873043617313360288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:34.906] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:108:0) after 1m0s
[23:06:34.941] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:108:0)
[23:06:34.941] Timer tick, asking Buildbucket for the build status
[23:06:35.059] Build 8873043617313360288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:35.059] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:109:0) after 1m0s
[23:07:35.156] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:109:0)
[23:07:35.156] Timer tick, asking Buildbucket for the build status
[23:07:35.240] Build 8873043617313360288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:35.240] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:110:0) after 1m0s
[23:08:35.291] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:110:0)
[23:08:35.291] Timer tick, asking Buildbucket for the build status
[23:08:35.414] Build 8873043617313360288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:35.414] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:111:0) after 1m0s
[23:09:35.524] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:111:0)
[23:09:35.524] Timer tick, asking Buildbucket for the build status
[23:09:35.627] Build 8873043617313360288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:35.627] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:112:0) after 1m0s
[23:10:35.654] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:112:0)
[23:10:35.654] Timer tick, asking Buildbucket for the build status
[23:10:35.787] Build 8873043617313360288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:35.787] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:113:0) after 1m0s
[23:11:35.815] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:113:0)
[23:11:35.815] Timer tick, asking Buildbucket for the build status
[23:11:35.934] Build 8873043617313360288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:35.934] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:114:0) after 1m0s
[23:12:36.007] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:114:0)
[23:12:36.007] Timer tick, asking Buildbucket for the build status
[23:12:36.092] Build 8873043617313360288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:36.092] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:115:0) after 1m0s
[23:13:36.186] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:115:0)
[23:13:36.186] Timer tick, asking Buildbucket for the build status
[23:13:36.308] Build 8873043617313360288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:36.308] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:116:0) after 1m0s
[23:14:36.343] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:116:0)
[23:14:36.343] Timer tick, asking Buildbucket for the build status
[23:14:36.411] Build 8873043617313360288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:36.411] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:117:0) after 1m0s
[23:15:36.452] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:117:0)
[23:15:36.452] Timer tick, asking Buildbucket for the build status
[23:15:36.587] Build 8873043617313360288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:36.587] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:118:0) after 1m0s
[23:16:36.625] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:118:0)
[23:16:36.625] Timer tick, asking Buildbucket for the build status
[23:16:36.760] Build 8873043617313360288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:36.760] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9038473679286318496:119:0) after 1m0s
[23:17:26.892] Received PubSub notification, asking Buildbucket for the build status
[23:17:26.969] Build 8873043617313360288: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:17:26.969] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1596410244180856",
"created_by": "project:infra",
"created_ts": "1596403216023485",
"id": "8873043617313360288",
"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\": \"481b158a4d6e45f10a1952a2642b8ef1d9c76de9\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@481b158a4d6e45f10a1952a2642b8ef1d9c76de9\", \"title\": \"481b158a4d6e45f10a1952a2642b8ef1d9c76de9\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/481b158a4d6e45f10a1952a2642b8ef1d9c76de9\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"481b158a4d6e45f10a1952a2642b8ef1d9c76de9\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"481b158a4d6e45f10a1952a2642b8ef1d9c76de9\", \"got_revision_cp\": \"refs/heads/master@{#33786}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_044445a3bc7a4bfe795493d7956aa02cd526172de1fa7300f69fe152e82ea631_v2\", \"builder_5de1032f0e9ef497433aad28840ee258c037976023b410f653133cee0ad04e2c_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"git\", \"goma\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-2cen\"], \"image\": [\"chrome-win10-1703-20073100-2eb11ddbc6e\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5457-b9aab91\"], \"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": "1596409227605718",
"status": "COMPLETED",
"status_changed_ts": "1596410244626833",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17418",
"builder:infra-continuous-win10-64",
"buildset:commit/git/481b158a4d6e45f10a1952a2642b8ef1d9c76de9",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/481b158a4d6e45f10a1952a2642b8ef1d9c76de9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9038473679286318496",
"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/8873043617313360288/+/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:4dc9dd7fb179f610",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1596410244637747",
"url": "https://luci-milo-dev.appspot.com/b/8873043617313360288",
"utcnow_ts": "1596410246961660"
}
}
[23:17:26.969] Invocation finished in 1h57m12.211162125s with status SUCCEEDED