[14:23:04.237] New invocation is queued and will start shortly
[14:23:05.352] Starting the invocation (attempt 1)
[14:23:05.400] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:23:05.400] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:23:05.400] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9038590522691639184",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"57c5fa4f0ff2c5a608a7110184234f816d23c469\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9038590522691639184",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/57c5fa4f0ff2c5a608a7110184234f816d23c469",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/57c5fa4f0ff2c5a608a7110184234f816d23c469",
"gitiles_ref:refs/heads/master"
]
}
[14:23:06.938] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1596291785505963",
"id": "8873160460680556032",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"57c5fa4f0ff2c5a608a7110184234f816d23c469\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1596291786630732",
"tags": [
"build_address:luci.chromium.ci/win-official/222",
"builder:win-official",
"buildset:commit/git/57c5fa4f0ff2c5a608a7110184234f816d23c469",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/57c5fa4f0ff2c5a608a7110184234f816d23c469",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9038590522691639184",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8873160460680556032/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1596291786631018",
"url": "https://ci.chromium.org/b/8873160460680556032",
"utcnow_ts": "1596291786920519"
}
}
[14:23:06.938] Task URL: https://ci.chromium.org/b/8873160460680556032
[14:23:06.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:2:0) after 1m0s
[14:23:08.920] Received PubSub notification, asking Buildbucket for the build status
[14:23:08.987] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:06.981] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:2:0)
[14:24:06.981] Timer tick, asking Buildbucket for the build status
[14:24:07.062] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:07.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:4:0) after 1m0s
[14:25:07.096] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:4:0)
[14:25:07.096] Timer tick, asking Buildbucket for the build status
[14:25:07.455] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:07.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:5:0) after 1m0s
[14:26:07.506] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:5:0)
[14:26:07.506] Timer tick, asking Buildbucket for the build status
[14:26:07.571] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:07.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:6:0) after 1m0s
[14:27:07.592] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:6:0)
[14:27:07.592] Timer tick, asking Buildbucket for the build status
[14:27:07.676] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:07.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:7:0) after 1m0s
[14:28:07.730] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:7:0)
[14:28:07.730] Timer tick, asking Buildbucket for the build status
[14:28:08.188] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:08.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:8:0) after 1m0s
[14:29:08.344] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:8:0)
[14:29:08.344] Timer tick, asking Buildbucket for the build status
[14:29:08.454] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:08.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:9:0) after 1m0s
[14:30:08.512] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:9:0)
[14:30:08.512] Timer tick, asking Buildbucket for the build status
[14:30:08.676] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:08.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:10:0) after 1m0s
[14:31:08.711] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:10:0)
[14:31:08.711] Timer tick, asking Buildbucket for the build status
[14:31:08.847] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:08.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:11:0) after 1m0s
[14:32:08.869] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:11:0)
[14:32:08.869] Timer tick, asking Buildbucket for the build status
[14:32:09.202] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:09.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:12:0) after 1m0s
[14:33:09.229] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:12:0)
[14:33:09.229] Timer tick, asking Buildbucket for the build status
[14:33:09.720] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:09.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:13:0) after 1m0s
[14:34:09.796] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:13:0)
[14:34:09.796] Timer tick, asking Buildbucket for the build status
[14:34:09.845] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:09.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:14:0) after 1m0s
[14:35:09.916] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:14:0)
[14:35:09.916] Timer tick, asking Buildbucket for the build status
[14:35:09.985] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:09.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:15:0) after 1m0s
[14:36:10.030] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:15:0)
[14:36:10.030] Timer tick, asking Buildbucket for the build status
[14:36:10.278] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:10.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:16:0) after 1m0s
[14:37:10.409] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:16:0)
[14:37:10.409] Timer tick, asking Buildbucket for the build status
[14:37:10.788] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:10.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:17:0) after 1m0s
[14:38:10.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:17:0)
[14:38:10.836] Timer tick, asking Buildbucket for the build status
[14:38:11.193] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:11.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:18:0) after 1m0s
[14:39:11.276] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:18:0)
[14:39:11.276] Timer tick, asking Buildbucket for the build status
[14:39:11.336] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:11.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:19:0) after 1m0s
[14:40:11.370] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:19:0)
[14:40:11.370] Timer tick, asking Buildbucket for the build status
[14:40:11.475] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:11.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:20:0) after 1m0s
[14:41:11.672] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:20:0)
[14:41:11.672] Timer tick, asking Buildbucket for the build status
[14:41:11.776] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:11.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:21:0) after 1m0s
[14:42:11.845] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:21:0)
[14:42:11.845] Timer tick, asking Buildbucket for the build status
[14:42:11.922] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:11.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:22:0) after 1m0s
[14:43:11.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:22:0)
[14:43:11.957] Timer tick, asking Buildbucket for the build status
[14:43:12.643] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:12.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:23:0) after 1m0s
[14:44:12.735] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:23:0)
[14:44:12.735] Timer tick, asking Buildbucket for the build status
[14:44:12.820] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:12.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:24:0) after 1m0s
[14:45:12.896] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:24:0)
[14:45:12.896] Timer tick, asking Buildbucket for the build status
[14:45:13.022] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:13.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:25:0) after 1m0s
[14:46:13.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:25:0)
[14:46:13.268] Timer tick, asking Buildbucket for the build status
[14:46:13.356] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:13.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:26:0) after 1m0s
[14:47:13.437] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:26:0)
[14:47:13.437] Timer tick, asking Buildbucket for the build status
[14:47:13.506] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:13.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:27:0) after 1m0s
[14:48:13.589] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:27:0)
[14:48:13.589] Timer tick, asking Buildbucket for the build status
[14:48:13.891] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:13.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:28:0) after 1m0s
[14:49:13.968] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:28:0)
[14:49:13.968] Timer tick, asking Buildbucket for the build status
[14:49:14.087] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:14.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:29:0) after 1m0s
[14:50:14.264] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:29:0)
[14:50:14.264] Timer tick, asking Buildbucket for the build status
[14:50:14.346] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:14.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:30:0) after 1m0s
[14:51:14.335] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:30:0)
[14:51:14.335] Timer tick, asking Buildbucket for the build status
[14:51:14.412] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:14.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:31:0) after 1m0s
[14:52:14.409] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:31:0)
[14:52:14.409] Timer tick, asking Buildbucket for the build status
[14:52:14.921] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:14.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:32:0) after 1m0s
[14:53:15.028] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:32:0)
[14:53:15.028] Timer tick, asking Buildbucket for the build status
[14:53:15.401] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:15.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:33:0) after 1m0s
[14:54:15.501] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:33:0)
[14:54:15.501] Timer tick, asking Buildbucket for the build status
[14:54:15.781] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:15.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:34:0) after 1m0s
[14:55:15.880] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:34:0)
[14:55:15.880] Timer tick, asking Buildbucket for the build status
[14:55:15.954] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:15.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:35:0) after 1m0s
[14:56:16.148] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:35:0)
[14:56:16.148] Timer tick, asking Buildbucket for the build status
[14:56:16.240] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:16.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:36:0) after 1m0s
[14:57:16.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:36:0)
[14:57:16.268] Timer tick, asking Buildbucket for the build status
[14:57:16.429] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:16.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:37:0) after 1m0s
[14:58:16.466] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:37:0)
[14:58:16.466] Timer tick, asking Buildbucket for the build status
[14:58:16.934] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:16.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:38:0) after 1m0s
[14:59:16.962] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:38:0)
[14:59:16.962] Timer tick, asking Buildbucket for the build status
[14:59:17.037] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:17.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:39:0) after 1m0s
[15:00:17.065] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:39:0)
[15:00:17.065] Timer tick, asking Buildbucket for the build status
[15:00:17.595] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:17.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:40:0) after 1m0s
[15:01:17.615] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:40:0)
[15:01:17.615] Timer tick, asking Buildbucket for the build status
[15:01:17.711] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:17.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:41:0) after 1m0s
[15:02:17.817] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:41:0)
[15:02:17.817] Timer tick, asking Buildbucket for the build status
[15:02:17.924] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:17.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:42:0) after 1m0s
[15:03:17.907] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:42:0)
[15:03:17.907] Timer tick, asking Buildbucket for the build status
[15:03:18.013] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:18.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:43:0) after 1m0s
[15:04:18.030] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:43:0)
[15:04:18.030] Timer tick, asking Buildbucket for the build status
[15:04:18.102] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:18.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:44:0) after 1m0s
[15:05:18.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:44:0)
[15:05:18.152] Timer tick, asking Buildbucket for the build status
[15:05:18.306] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:18.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:45:0) after 1m0s
[15:06:18.303] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:45:0)
[15:06:18.303] Timer tick, asking Buildbucket for the build status
[15:06:18.382] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:18.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:46:0) after 1m0s
[15:07:18.468] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:46:0)
[15:07:18.468] Timer tick, asking Buildbucket for the build status
[15:07:18.559] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:18.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:47:0) after 1m0s
[15:08:18.682] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:47:0)
[15:08:18.682] Timer tick, asking Buildbucket for the build status
[15:08:18.833] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:18.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:48:0) after 1m0s
[15:09:18.907] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:48:0)
[15:09:18.907] Timer tick, asking Buildbucket for the build status
[15:09:18.982] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:18.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:49:0) after 1m0s
[15:10:19.038] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:49:0)
[15:10:19.038] Timer tick, asking Buildbucket for the build status
[15:10:19.246] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:19.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:50:0) after 1m0s
[15:11:19.272] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:50:0)
[15:11:19.272] Timer tick, asking Buildbucket for the build status
[15:11:19.344] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:19.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:51:0) after 1m0s
[15:12:19.379] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:51:0)
[15:12:19.379] Timer tick, asking Buildbucket for the build status
[15:12:19.441] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:19.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:52:0) after 1m0s
[15:13:19.537] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:52:0)
[15:13:19.537] Timer tick, asking Buildbucket for the build status
[15:13:19.713] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:19.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:53:0) after 1m0s
[15:14:19.774] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:53:0)
[15:14:19.774] Timer tick, asking Buildbucket for the build status
[15:14:20.017] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:20.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:54:0) after 1m0s
[15:15:20.045] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:54:0)
[15:15:20.045] Timer tick, asking Buildbucket for the build status
[15:15:20.260] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:20.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:55:0) after 1m0s
[15:16:20.272] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:55:0)
[15:16:20.272] Timer tick, asking Buildbucket for the build status
[15:16:20.339] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:20.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:56:0) after 1m0s
[15:17:20.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:56:0)
[15:17:20.414] Timer tick, asking Buildbucket for the build status
[15:17:20.673] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:20.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:57:0) after 1m0s
[15:18:20.742] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:57:0)
[15:18:20.742] Timer tick, asking Buildbucket for the build status
[15:18:20.876] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:20.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:58:0) after 1m0s
[15:19:20.920] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:58:0)
[15:19:20.920] Timer tick, asking Buildbucket for the build status
[15:19:21.007] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:21.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:59:0) after 1m0s
[15:20:21.167] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:59:0)
[15:20:21.167] Timer tick, asking Buildbucket for the build status
[15:20:21.307] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:21.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:60:0) after 1m0s
[15:21:21.449] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:60:0)
[15:21:21.449] Timer tick, asking Buildbucket for the build status
[15:21:21.544] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:21.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:61:0) after 1m0s
[15:22:21.659] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:61:0)
[15:22:21.659] Timer tick, asking Buildbucket for the build status
[15:22:21.840] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:21.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:62:0) after 1m0s
[15:23:22.086] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:62:0)
[15:23:22.086] Timer tick, asking Buildbucket for the build status
[15:23:22.272] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:22.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:63:0) after 1m0s
[15:24:22.341] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:63:0)
[15:24:22.341] Timer tick, asking Buildbucket for the build status
[15:24:22.411] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:22.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:64:0) after 1m0s
[15:25:22.509] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:64:0)
[15:25:22.509] Timer tick, asking Buildbucket for the build status
[15:25:22.725] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:22.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:65:0) after 1m0s
[15:26:22.817] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:65:0)
[15:26:22.817] Timer tick, asking Buildbucket for the build status
[15:26:22.893] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:22.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:66:0) after 1m0s
[15:27:22.952] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:66:0)
[15:27:22.952] Timer tick, asking Buildbucket for the build status
[15:27:23.509] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:23.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:67:0) after 1m0s
[15:28:23.591] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:67:0)
[15:28:23.591] Timer tick, asking Buildbucket for the build status
[15:28:23.742] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:23.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:68:0) after 1m0s
[15:29:23.783] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:68:0)
[15:29:23.783] Timer tick, asking Buildbucket for the build status
[15:29:25.048] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:25.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:69:0) after 1m0s
[15:30:25.147] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:69:0)
[15:30:25.147] Timer tick, asking Buildbucket for the build status
[15:30:25.229] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:25.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:70:0) after 1m0s
[15:31:25.352] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:70:0)
[15:31:25.352] Timer tick, asking Buildbucket for the build status
[15:31:25.442] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:25.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:71:0) after 1m0s
[15:32:25.488] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:71:0)
[15:32:25.488] Timer tick, asking Buildbucket for the build status
[15:32:25.626] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:25.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:72:0) after 1m0s
[15:33:25.667] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:72:0)
[15:33:25.667] Timer tick, asking Buildbucket for the build status
[15:33:25.812] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:25.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:73:0) after 1m0s
[15:34:25.889] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:73:0)
[15:34:25.889] Timer tick, asking Buildbucket for the build status
[15:34:25.948] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:25.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:74:0) after 1m0s
[15:35:25.941] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:74:0)
[15:35:25.941] Timer tick, asking Buildbucket for the build status
[15:35:26.016] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:26.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:75:0) after 1m0s
[15:36:26.089] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:75:0)
[15:36:26.089] Timer tick, asking Buildbucket for the build status
[15:36:26.187] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:26.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:76:0) after 1m0s
[15:37:26.273] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:76:0)
[15:37:26.273] Timer tick, asking Buildbucket for the build status
[15:37:26.438] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:26.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:77:0) after 1m0s
[15:38:26.507] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:77:0)
[15:38:26.507] Timer tick, asking Buildbucket for the build status
[15:38:26.621] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:26.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:78:0) after 1m0s
[15:39:26.676] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:78:0)
[15:39:26.676] Timer tick, asking Buildbucket for the build status
[15:39:26.765] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:26.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:79:0) after 1m0s
[15:40:26.822] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:79:0)
[15:40:26.822] Timer tick, asking Buildbucket for the build status
[15:40:27.008] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:27.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:80:0) after 1m0s
[15:41:27.047] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:80:0)
[15:41:27.047] Timer tick, asking Buildbucket for the build status
[15:41:27.159] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:27.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:81:0) after 1m0s
[15:42:27.225] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:81:0)
[15:42:27.225] Timer tick, asking Buildbucket for the build status
[15:42:27.284] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:27.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:82:0) after 1m0s
[15:43:27.294] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:82:0)
[15:43:27.294] Timer tick, asking Buildbucket for the build status
[15:43:27.422] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:27.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:83:0) after 1m0s
[15:44:27.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:83:0)
[15:44:27.555] Timer tick, asking Buildbucket for the build status
[15:44:27.614] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:27.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:84:0) after 1m0s
[15:45:27.731] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:84:0)
[15:45:27.731] Timer tick, asking Buildbucket for the build status
[15:45:27.810] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:27.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:85:0) after 1m0s
[15:46:27.884] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:85:0)
[15:46:27.884] Timer tick, asking Buildbucket for the build status
[15:46:27.954] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:27.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:86:0) after 1m0s
[15:47:27.947] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:86:0)
[15:47:27.947] Timer tick, asking Buildbucket for the build status
[15:47:28.025] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:28.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:87:0) after 1m0s
[15:48:28.071] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:87:0)
[15:48:28.071] Timer tick, asking Buildbucket for the build status
[15:48:28.205] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:28.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:88:0) after 1m0s
[15:49:28.235] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:88:0)
[15:49:28.235] Timer tick, asking Buildbucket for the build status
[15:49:28.374] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:28.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:89:0) after 1m0s
[15:50:28.402] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:89:0)
[15:50:28.402] Timer tick, asking Buildbucket for the build status
[15:50:28.532] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:28.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:90:0) after 1m0s
[15:51:28.525] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:90:0)
[15:51:28.525] Timer tick, asking Buildbucket for the build status
[15:51:28.815] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:28.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:91:0) after 1m0s
[15:52:28.856] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:91:0)
[15:52:28.856] Timer tick, asking Buildbucket for the build status
[15:52:28.930] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:28.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:92:0) after 1m0s
[15:53:29.004] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:92:0)
[15:53:29.004] Timer tick, asking Buildbucket for the build status
[15:53:29.264] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:29.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:93:0) after 1m0s
[15:54:29.332] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:93:0)
[15:54:29.332] Timer tick, asking Buildbucket for the build status
[15:54:29.422] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:29.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:94:0) after 1m0s
[15:55:29.584] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:94:0)
[15:55:29.584] Timer tick, asking Buildbucket for the build status
[15:55:29.943] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:29.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:95:0) after 1m0s
[15:56:30.040] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:95:0)
[15:56:30.040] Timer tick, asking Buildbucket for the build status
[15:56:30.291] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:30.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:96:0) after 1m0s
[15:57:30.318] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:96:0)
[15:57:30.318] Timer tick, asking Buildbucket for the build status
[15:57:30.703] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:30.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:97:0) after 1m0s
[15:58:30.848] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:97:0)
[15:58:30.848] Timer tick, asking Buildbucket for the build status
[15:58:30.987] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:30.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:98:0) after 1m0s
[15:59:31.046] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:98:0)
[15:59:31.046] Timer tick, asking Buildbucket for the build status
[15:59:31.127] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:31.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:99:0) after 1m0s
[16:00:31.170] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:99:0)
[16:00:31.170] Timer tick, asking Buildbucket for the build status
[16:00:31.247] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:31.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:100:0) after 1m0s
[16:01:31.266] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:100:0)
[16:01:31.266] Timer tick, asking Buildbucket for the build status
[16:01:31.350] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:31.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:101:0) after 1m0s
[16:02:31.375] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:101:0)
[16:02:31.375] Timer tick, asking Buildbucket for the build status
[16:02:31.441] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:31.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:102:0) after 1m0s
[16:03:31.478] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:102:0)
[16:03:31.478] Timer tick, asking Buildbucket for the build status
[16:03:31.646] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:31.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:103:0) after 1m0s
[16:04:31.723] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:103:0)
[16:04:31.723] Timer tick, asking Buildbucket for the build status
[16:04:31.833] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:31.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:104:0) after 1m0s
[16:05:31.877] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:104:0)
[16:05:31.877] Timer tick, asking Buildbucket for the build status
[16:05:31.961] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:31.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:105:0) after 1m0s
[16:06:32.075] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:105:0)
[16:06:32.077] Timer tick, asking Buildbucket for the build status
[16:06:32.227] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:32.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:106:0) after 1m0s
[16:07:32.309] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:106:0)
[16:07:32.309] Timer tick, asking Buildbucket for the build status
[16:07:32.392] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:32.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:107:0) after 1m0s
[16:08:32.466] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:107:0)
[16:08:32.466] Timer tick, asking Buildbucket for the build status
[16:08:32.691] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:32.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:108:0) after 1m0s
[16:09:32.716] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:108:0)
[16:09:32.716] Timer tick, asking Buildbucket for the build status
[16:09:32.912] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:32.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:109:0) after 1m0s
[16:10:32.998] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:109:0)
[16:10:32.998] Timer tick, asking Buildbucket for the build status
[16:10:33.216] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:33.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:110:0) after 1m0s
[16:11:33.299] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:110:0)
[16:11:33.299] Timer tick, asking Buildbucket for the build status
[16:11:33.377] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:33.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:111:0) after 1m0s
[16:12:33.452] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:111:0)
[16:12:33.452] Timer tick, asking Buildbucket for the build status
[16:12:33.525] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:33.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:112:0) after 1m0s
[16:13:33.615] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:112:0)
[16:13:33.615] Timer tick, asking Buildbucket for the build status
[16:13:33.987] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:33.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:113:0) after 1m0s
[16:14:34.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:113:0)
[16:14:34.152] Timer tick, asking Buildbucket for the build status
[16:14:34.226] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:34.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:114:0) after 1m0s
[16:15:34.318] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:114:0)
[16:15:34.318] Timer tick, asking Buildbucket for the build status
[16:15:34.569] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:34.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:115:0) after 1m0s
[16:16:34.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:115:0)
[16:16:34.666] Timer tick, asking Buildbucket for the build status
[16:16:34.802] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:34.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:116:0) after 1m0s
[16:17:34.924] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:116:0)
[16:17:34.924] Timer tick, asking Buildbucket for the build status
[16:17:35.011] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:35.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:117:0) after 1m0s
[16:18:35.035] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:117:0)
[16:18:35.035] Timer tick, asking Buildbucket for the build status
[16:18:35.415] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:35.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:118:0) after 1m0s
[16:19:35.439] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:118:0)
[16:19:35.439] Timer tick, asking Buildbucket for the build status
[16:19:35.723] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:35.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:119:0) after 1m0s
[16:20:35.787] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:119:0)
[16:20:35.787] Timer tick, asking Buildbucket for the build status
[16:20:35.927] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:35.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:120:0) after 1m0s
[16:21:35.962] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:120:0)
[16:21:35.962] Timer tick, asking Buildbucket for the build status
[16:21:36.082] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:36.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:121:0) after 1m0s
[16:22:36.111] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:121:0)
[16:22:36.111] Timer tick, asking Buildbucket for the build status
[16:22:36.217] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:36.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:122:0) after 1m0s
[16:23:36.364] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:122:0)
[16:23:36.364] Timer tick, asking Buildbucket for the build status
[16:23:36.502] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:36.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:123:0) after 1m0s
[16:24:36.608] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:123:0)
[16:24:36.608] Timer tick, asking Buildbucket for the build status
[16:24:36.779] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:36.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:124:0) after 1m0s
[16:25:36.807] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:124:0)
[16:25:36.808] Timer tick, asking Buildbucket for the build status
[16:25:36.875] Build 8873160460680556032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:36.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038590522691639184:125:0) after 1m0s
[16:26:05.684] Received PubSub notification, asking Buildbucket for the build status
[16:26:05.812] Build 8873160460680556032: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:26:05.812] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1596299164715420",
"created_by": "project:chromium",
"created_ts": "1596291785505963",
"id": "8873160460680556032",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"57c5fa4f0ff2c5a608a7110184234f816d23c469\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-0-fdfa\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 222, \"got_angle_revision\": \"d04a55932c4cc4c5174dbe4b63d389e8cbd5dfa5\", \"got_dawn_revision\": \"fada50119020483021a6b620c8fb85d62fc3e8f0\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"57c5fa4f0ff2c5a608a7110184234f816d23c469\", \"got_revision_cp\": \"refs/heads/master@{#793903}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"aa5bd7657e6cc140b95f3f070f011c65213a2cc1\", \"got_v8_revision\": \"33aadb77fef52be264780851920ccc7d3669c7c2\", \"got_v8_revision_cp\": \"refs/heads/8.6.314@{#1}\", \"got_webrtc_revision\": \"353dcec4c1e839753875ed515e106647dc241e39\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31825}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"57c5fa4f0ff2c5a608a7110184234f816d23c469\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-0-fdfa\"], \"image\": [\"chrome-win10-1703-20071600-faa8609efbc\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5436-adf7f8a\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1596291787929349",
"status": "COMPLETED",
"status_changed_ts": "1596299165268812",
"tags": [
"build_address:luci.chromium.ci/win-official/222",
"builder:win-official",
"buildset:commit/git/57c5fa4f0ff2c5a608a7110184234f816d23c469",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/57c5fa4f0ff2c5a608a7110184234f816d23c469",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9038590522691639184",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8873160460680556032/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4dc3393210526610",
"user_agent:luci-scheduler"
],
"updated_ts": "1596299165277837",
"url": "https://ci.chromium.org/b/8873160460680556032",
"utcnow_ts": "1596299165802645"
}
}
[16:26:05.812] Invocation finished in 2h3m1.607608735s with status SUCCEEDED